{"level":"warn","ts":"2026-04-20T10:16:16.046927Z","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-20T10:16:16.047027Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-20T10:16:16.047109Z","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-20T10:16:16.047131Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-20T10:16:16.047159Z","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-20T10:16:16.047873Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-20T10:16:16.047957Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-20T10:16:16.052197Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.982072ms"} {"level":"info","ts":"2026-04-20T10:16:16.057747Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-20T10:16:16.057826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T10:16:16.057846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-20T10:16:16.057862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T10:16:16.057868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-20T10:16:16.057902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-20T10:16:16.06646Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T10:16:16.068816Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T10:16:16.070074Z","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-20T10:16:16.072139Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T10:16:16.072408Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T10:16:16.072435Z","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-20T10:16:16.073377Z","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-20T10:16:16.073554Z","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-20T10:16:16.074086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-20T10:16:16.074874Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-20T10:16:16.077428Z","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-20T10:16:16.077541Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-20T10:16:16.07763Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-20T10:16:16.077782Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T10:16:16.077821Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T10:16:16.558581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T10:16:16.558658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T10:16:16.558704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-20T10:16:16.558727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-20T10:16:16.558739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-20T10:16:16.558762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-20T10:16:16.558777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-20T10:16:16.566326Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:16:16.571961Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T10:16:16.571969Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:16:16.572055Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:16:16.574625Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-20T10:16:16.575705Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T10:16:16.575745Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T10:16:16.57637Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:16:16.576447Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:16:16.576467Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:16:16.576963Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T10:16:26.774356Z","caller":"traceutil/trace.go:171","msg":"trace[1699054437] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"155.995059ms","start":"2026-04-20T10:16:26.618327Z","end":"2026-04-20T10:16:26.774322Z","steps":["trace[1699054437] 'process raft request' (duration: 110.461278ms)","trace[1699054437] 'compare' (duration: 45.339336ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:26.785358Z","caller":"traceutil/trace.go:171","msg":"trace[906787242] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:323; }","duration":"159.857338ms","start":"2026-04-20T10:16:26.62548Z","end":"2026-04-20T10:16:26.785337Z","steps":["trace[906787242] 'read index received' (duration: 103.324224ms)","trace[906787242] 'applied index is now lower than readState.Index' (duration: 56.532154ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:26.785381Z","caller":"traceutil/trace.go:171","msg":"trace[2142014443] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"160.737328ms","start":"2026-04-20T10:16:26.624619Z","end":"2026-04-20T10:16:26.785357Z","steps":["trace[2142014443] 'process raft request' (duration: 160.591845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:26.785661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.149794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-04-20T10:16:26.785809Z","caller":"traceutil/trace.go:171","msg":"trace[1917740311] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:315; }","duration":"160.346439ms","start":"2026-04-20T10:16:26.625434Z","end":"2026-04-20T10:16:26.785781Z","steps":["trace[1917740311] 'agreement among raft nodes before linearized reading' (duration: 160.054423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:26.814661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.5147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-20T10:16:26.815337Z","caller":"traceutil/trace.go:171","msg":"trace[1474938393] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:317; }","duration":"134.193846ms","start":"2026-04-20T10:16:26.681088Z","end":"2026-04-20T10:16:26.815281Z","steps":["trace[1474938393] 'agreement among raft nodes before linearized reading' (duration: 133.49718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:26.815029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.230647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-20T10:16:26.81549Z","caller":"traceutil/trace.go:171","msg":"trace[1538797975] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:317; }","duration":"186.705957ms","start":"2026-04-20T10:16:26.628765Z","end":"2026-04-20T10:16:26.815471Z","steps":["trace[1538797975] 'agreement among raft nodes before linearized reading' (duration: 186.013071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:36.009344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.955591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:36.009499Z","caller":"traceutil/trace.go:171","msg":"trace[1107472922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:382; }","duration":"271.183827ms","start":"2026-04-20T10:16:35.73829Z","end":"2026-04-20T10:16:36.009474Z","steps":["trace[1107472922] 'range keys from in-memory index tree' (duration: 270.85915ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:36.504211Z","caller":"traceutil/trace.go:171","msg":"trace[2042556418] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"122.635997ms","start":"2026-04-20T10:16:36.381527Z","end":"2026-04-20T10:16:36.504163Z","steps":["trace[2042556418] 'process raft request' (duration: 122.280879ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:36.73737Z","caller":"traceutil/trace.go:171","msg":"trace[838408674] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"217.884779ms","start":"2026-04-20T10:16:36.519432Z","end":"2026-04-20T10:16:36.737317Z","steps":["trace[838408674] 'process raft request' (duration: 217.634363ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:50.74339Z","caller":"traceutil/trace.go:171","msg":"trace[949381185] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"159.186516ms","start":"2026-04-20T10:16:50.584132Z","end":"2026-04-20T10:16:50.743318Z","steps":["trace[949381185] 'process raft request' (duration: 158.782137ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:56.7895Z","caller":"traceutil/trace.go:171","msg":"trace[2121002733] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"101.650481ms","start":"2026-04-20T10:16:56.687815Z","end":"2026-04-20T10:16:56.789465Z","steps":["trace[2121002733] 'read index received' (duration: 99.922411ms)","trace[2121002733] 'applied index is now lower than readState.Index' (duration: 1.72652ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:56.791786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.962204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2f4xd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T10:16:56.791846Z","caller":"traceutil/trace.go:171","msg":"trace[1192244209] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2f4xd; range_end:; response_count:1; response_revision:583; }","duration":"104.072166ms","start":"2026-04-20T10:16:56.687762Z","end":"2026-04-20T10:16:56.791834Z","steps":["trace[1192244209] 'agreement among raft nodes before linearized reading' (duration: 103.900662ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:56.792019Z","caller":"traceutil/trace.go:171","msg":"trace[303577226] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"144.305261ms","start":"2026-04-20T10:16:56.647704Z","end":"2026-04-20T10:16:56.79201Z","steps":["trace[303577226] 'process raft request' (duration: 140.697537ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:56.923547Z","caller":"traceutil/trace.go:171","msg":"trace[1814911293] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"113.490395ms","start":"2026-04-20T10:16:56.810023Z","end":"2026-04-20T10:16:56.923513Z","steps":["trace[1814911293] 'process raft request' (duration: 113.28901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:57.171398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.349043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:16:57.171504Z","caller":"traceutil/trace.go:171","msg":"trace[542671747] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"241.750073ms","start":"2026-04-20T10:16:56.929738Z","end":"2026-04-20T10:16:57.171488Z","steps":["trace[542671747] 'read index received' (duration: 80.712564ms)","trace[542671747] 'applied index is now lower than readState.Index' (duration: 161.036309ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:16:57.17155Z","caller":"traceutil/trace.go:171","msg":"trace[110880463] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"309.921867ms","start":"2026-04-20T10:16:56.861589Z","end":"2026-04-20T10:16:57.171511Z","steps":["trace[110880463] 'process raft request' (duration: 148.939369ms)","trace[110880463] 'compare' (duration: 159.949344ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:57.171666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.895276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:57.171719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.832385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:57.171734Z","caller":"traceutil/trace.go:171","msg":"trace[712750108] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:586; }","duration":"242.02903ms","start":"2026-04-20T10:16:56.929685Z","end":"2026-04-20T10:16:57.171714Z","steps":["trace[712750108] 'agreement among raft nodes before linearized reading' (duration: 241.854226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:57.171711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:56.861559Z","time spent":"310.08443ms","remote":"127.0.0.1:40024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:16:57.171765Z","caller":"traceutil/trace.go:171","msg":"trace[2035958548] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:586; }","duration":"241.952818ms","start":"2026-04-20T10:16:56.929803Z","end":"2026-04-20T10:16:57.171756Z","steps":["trace[2035958548] 'agreement among raft nodes before linearized reading' (duration: 241.822555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:57.171755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.954102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:57.171847Z","caller":"traceutil/trace.go:171","msg":"trace[2055678691] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:586; }","duration":"153.093215ms","start":"2026-04-20T10:16:57.018733Z","end":"2026-04-20T10:16:57.171826Z","steps":["trace[2055678691] 'agreement among raft nodes before linearized reading' (duration: 152.90172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:57.171935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.886957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:57.171982Z","caller":"traceutil/trace.go:171","msg":"trace[1346490103] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"204.98775ms","start":"2026-04-20T10:16:56.966981Z","end":"2026-04-20T10:16:57.171969Z","steps":["trace[1346490103] 'agreement among raft nodes before linearized reading' (duration: 204.892868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:57.172005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.341118ms","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-20T10:16:57.172045Z","caller":"traceutil/trace.go:171","msg":"trace[1038155178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"174.446691ms","start":"2026-04-20T10:16:56.997588Z","end":"2026-04-20T10:16:57.172035Z","steps":["trace[1038155178] 'agreement among raft nodes before linearized reading' (duration: 174.319208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:57.172049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.15995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:57.172037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.162584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:57.172128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.057827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:57.172148Z","caller":"traceutil/trace.go:171","msg":"trace[2023891286] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"205.289177ms","start":"2026-04-20T10:16:56.966827Z","end":"2026-04-20T10:16:57.172116Z","steps":["trace[2023891286] 'agreement among raft nodes before linearized reading' (duration: 205.093172ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:57.172166Z","caller":"traceutil/trace.go:171","msg":"trace[1477104844] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:586; }","duration":"154.130899ms","start":"2026-04-20T10:16:57.018024Z","end":"2026-04-20T10:16:57.172155Z","steps":["trace[1477104844] 'agreement among raft nodes before linearized reading' (duration: 154.083217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:57.172231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.754395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:57.172267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.213614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:57.172024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.160424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:57.172294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.276666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:57.172388Z","caller":"traceutil/trace.go:171","msg":"trace[950943443] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:586; }","duration":"242.264366ms","start":"2026-04-20T10:16:56.930025Z","end":"2026-04-20T10:16:57.17229Z","steps":["trace[950943443] 'agreement among raft nodes before linearized reading' (duration: 242.211154ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:57.172412Z","caller":"traceutil/trace.go:171","msg":"trace[185476334] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"174.41505ms","start":"2026-04-20T10:16:56.997953Z","end":"2026-04-20T10:16:57.172368Z","steps":["trace[185476334] 'agreement among raft nodes before linearized reading' (duration: 174.160134ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:57.172359Z","caller":"traceutil/trace.go:171","msg":"trace[12514960] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"173.843756ms","start":"2026-04-20T10:16:56.998437Z","end":"2026-04-20T10:16:57.17228Z","steps":["trace[12514960] 'agreement among raft nodes before linearized reading' (duration: 173.669672ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:57.172482Z","caller":"traceutil/trace.go:171","msg":"trace[926752588] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"174.552784ms","start":"2026-04-20T10:16:56.997816Z","end":"2026-04-20T10:16:57.172368Z","steps":["trace[926752588] 'agreement among raft nodes before linearized reading' (duration: 174.014841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:57.172553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.100705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:57.172571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.309389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:57.17259Z","caller":"traceutil/trace.go:171","msg":"trace[1615851946] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:586; }","duration":"128.299039ms","start":"2026-04-20T10:16:57.044282Z","end":"2026-04-20T10:16:57.172581Z","steps":["trace[1615851946] 'agreement among raft nodes before linearized reading' (duration: 128.243658ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:57.17261Z","caller":"traceutil/trace.go:171","msg":"trace[1847353803] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:586; }","duration":"128.380771ms","start":"2026-04-20T10:16:57.044219Z","end":"2026-04-20T10:16:57.1726Z","steps":["trace[1847353803] 'agreement among raft nodes before linearized reading' (duration: 128.32389ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:57.172087Z","caller":"traceutil/trace.go:171","msg":"trace[1236558928] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:586; }","duration":"154.237212ms","start":"2026-04-20T10:16:57.017839Z","end":"2026-04-20T10:16:57.172077Z","steps":["trace[1236558928] 'agreement among raft nodes before linearized reading' (duration: 154.17877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:57.172746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.081594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:57.172896Z","caller":"traceutil/trace.go:171","msg":"trace[1201779610] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:586; }","duration":"128.289419ms","start":"2026-04-20T10:16:57.044569Z","end":"2026-04-20T10:16:57.172859Z","steps":["trace[1201779610] 'agreement among raft nodes before linearized reading' (duration: 127.959981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.36363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029999446s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:16:58.363774Z","caller":"traceutil/trace.go:171","msg":"trace[1052727513] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"1.181374781s","start":"2026-04-20T10:16:57.182376Z","end":"2026-04-20T10:16:58.363751Z","steps":["trace[1052727513] 'read index received' (duration: 150.869023ms)","trace[1052727513] 'applied index is now lower than readState.Index' (duration: 1.030504348s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:58.363895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181520715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:58.363932Z","caller":"traceutil/trace.go:171","msg":"trace[776140736] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:587; }","duration":"1.181584506s","start":"2026-04-20T10:16:57.182335Z","end":"2026-04-20T10:16:58.36392Z","steps":["trace[776140736] 'agreement among raft nodes before linearized reading' (duration: 1.181518335s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:58.363893Z","caller":"traceutil/trace.go:171","msg":"trace[165298075] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.185462356s","start":"2026-04-20T10:16:57.178395Z","end":"2026-04-20T10:16:58.363857Z","steps":["trace[165298075] 'process raft request' (duration: 154.880486ms)","trace[165298075] 'compare' (duration: 1.029823582s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:16:58.363976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.182324Z","time spent":"1.181638687s","remote":"127.0.0.1:40152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-04-20T10:16:58.364042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084341248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:58.364065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04564304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:58.36409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.178369Z","time spent":"1.18561728s","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:16:58.364075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.973565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:58.364138Z","caller":"traceutil/trace.go:171","msg":"trace[923877713] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:587; }","duration":"1.045774783s","start":"2026-04-20T10:16:57.318344Z","end":"2026-04-20T10:16:58.364118Z","steps":["trace[923877713] 'agreement among raft nodes before linearized reading' (duration: 1.04563525s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:58.364169Z","caller":"traceutil/trace.go:171","msg":"trace[207987099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"623.14195ms","start":"2026-04-20T10:16:57.741007Z","end":"2026-04-20T10:16:58.364149Z","steps":["trace[207987099] 'agreement among raft nodes before linearized reading' (duration: 622.973816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.36419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.318322Z","time spent":"1.045858074s","remote":"127.0.0.1:47710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-20T10:16:58.364214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062775078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:58.364216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.740983Z","time spent":"623.223361ms","remote":"127.0.0.1:39716","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-20T10:16:58.364248Z","caller":"traceutil/trace.go:171","msg":"trace[912993600] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:587; }","duration":"1.062822149s","start":"2026-04-20T10:16:57.301418Z","end":"2026-04-20T10:16:58.36424Z","steps":["trace[912993600] 'agreement among raft nodes before linearized reading' (duration: 1.062774668s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.364291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.30141Z","time spent":"1.06287161s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"info","ts":"2026-04-20T10:16:58.364112Z","caller":"traceutil/trace.go:171","msg":"trace[1739847657] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:587; }","duration":"1.084446331s","start":"2026-04-20T10:16:57.279646Z","end":"2026-04-20T10:16:58.364092Z","steps":["trace[1739847657] 'agreement among raft nodes before linearized reading' (duration: 1.084326069s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.364345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175774962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:58.36438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063592207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:58.364465Z","caller":"traceutil/trace.go:171","msg":"trace[209952142] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:587; }","duration":"1.175948345s","start":"2026-04-20T10:16:57.188487Z","end":"2026-04-20T10:16:58.364435Z","steps":["trace[209952142] 'agreement among raft nodes before linearized reading' (duration: 1.175478024s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.364494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021811136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T10:16:58.364494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063785251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:58.364529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084959063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:58.364533Z","caller":"traceutil/trace.go:171","msg":"trace[1161887956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"1.021866457s","start":"2026-04-20T10:16:57.342658Z","end":"2026-04-20T10:16:58.364524Z","steps":["trace[1161887956] 'agreement among raft nodes before linearized reading' (duration: 1.021724834s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:58.364539Z","caller":"traceutil/trace.go:171","msg":"trace[1691086089] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:587; }","duration":"1.063833392s","start":"2026-04-20T10:16:57.300696Z","end":"2026-04-20T10:16:58.36453Z","steps":["trace[1691086089] 'agreement among raft nodes before linearized reading' (duration: 1.063764521s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:58.364506Z","caller":"traceutil/trace.go:171","msg":"trace[180752828] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:587; }","duration":"1.06376425s","start":"2026-04-20T10:16:57.300716Z","end":"2026-04-20T10:16:58.364481Z","steps":["trace[180752828] 'agreement among raft nodes before linearized reading' (duration: 1.063511905s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:58.364564Z","caller":"traceutil/trace.go:171","msg":"trace[955124719] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:587; }","duration":"1.085029414s","start":"2026-04-20T10:16:57.279523Z","end":"2026-04-20T10:16:58.364553Z","steps":["trace[955124719] 'agreement among raft nodes before linearized reading' (duration: 1.084977223s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.364567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.342646Z","time spent":"1.021915459s","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T10:16:58.364587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.300687Z","time spent":"1.063891264s","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T10:16:58.364603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.279504Z","time spent":"1.085090596s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T10:16:58.364611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.300713Z","time spent":"1.063886083s","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:16:58.364622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046197882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:58.364653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17612661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:58.364666Z","caller":"traceutil/trace.go:171","msg":"trace[874415361] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:587; }","duration":"1.046268094s","start":"2026-04-20T10:16:57.318389Z","end":"2026-04-20T10:16:58.364657Z","steps":["trace[874415361] 'agreement among raft nodes before linearized reading' (duration: 1.046201013s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:58.364697Z","caller":"traceutil/trace.go:171","msg":"trace[1627035954] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:587; }","duration":"1.176182691s","start":"2026-04-20T10:16:57.188504Z","end":"2026-04-20T10:16:58.364687Z","steps":["trace[1627035954] 'agreement among raft nodes before linearized reading' (duration: 1.17612124s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.364896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148743974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:58.364603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148129339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:58.364919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.188494Z","time spent":"1.176416706s","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T10:16:58.364935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111820336s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:58.364641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175669139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:58.364169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045910526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:58.365019Z","caller":"traceutil/trace.go:171","msg":"trace[1002956518] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:587; }","duration":"1.11195156s","start":"2026-04-20T10:16:57.253056Z","end":"2026-04-20T10:16:58.365007Z","steps":["trace[1002956518] 'agreement among raft nodes before linearized reading' (duration: 1.111656313s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:58.36496Z","caller":"traceutil/trace.go:171","msg":"trace[110308787] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:587; }","duration":"1.148522768s","start":"2026-04-20T10:16:57.216425Z","end":"2026-04-20T10:16:58.364948Z","steps":["trace[110308787] 'agreement among raft nodes before linearized reading' (duration: 1.14814931s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.364705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.318371Z","time spent":"1.046324825s","remote":"127.0.0.1:47710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-04-20T10:16:58.365041Z","caller":"traceutil/trace.go:171","msg":"trace[354969808] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:587; }","duration":"1.046791516s","start":"2026-04-20T10:16:57.318239Z","end":"2026-04-20T10:16:58.36503Z","steps":["trace[354969808] 'agreement among raft nodes before linearized reading' (duration: 1.045902565s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.365062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.253033Z","time spent":"1.112017941s","remote":"127.0.0.1:47680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T10:16:58.365069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.216405Z","time spent":"1.148649932s","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:16:58.365082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.318231Z","time spent":"1.046838438s","remote":"127.0.0.1:47710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:16:58.365011Z","caller":"traceutil/trace.go:171","msg":"trace[1975049122] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:587; }","duration":"1.148997189s","start":"2026-04-20T10:16:57.215949Z","end":"2026-04-20T10:16:58.364946Z","steps":["trace[1975049122] 'agreement among raft nodes before linearized reading' (duration: 1.148728993s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.365104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111124431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:58.364443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.279634Z","time spent":"1.084791199s","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:16:58.365156Z","caller":"traceutil/trace.go:171","msg":"trace[181348806] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:587; }","duration":"1.111197442s","start":"2026-04-20T10:16:57.253948Z","end":"2026-04-20T10:16:58.365146Z","steps":["trace[181348806] 'agreement among raft nodes before linearized reading' (duration: 1.11112173s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.365164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111947339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:16:58.365165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.215941Z","time spent":"1.149205605s","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T10:16:58.365188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.253935Z","time spent":"1.111243802s","remote":"127.0.0.1:47680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-20T10:16:58.364533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.188471Z","time spent":"1.176048828s","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:16:58.365197Z","caller":"traceutil/trace.go:171","msg":"trace[684125518] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:587; }","duration":"1.11198313s","start":"2026-04-20T10:16:57.253204Z","end":"2026-04-20T10:16:58.365187Z","steps":["trace[684125518] 'agreement among raft nodes before linearized reading' (duration: 1.111868377s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:16:58.365033Z","caller":"traceutil/trace.go:171","msg":"trace[1860345015] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:587; }","duration":"1.176077969s","start":"2026-04-20T10:16:57.188943Z","end":"2026-04-20T10:16:58.365021Z","steps":["trace[1860345015] 'agreement among raft nodes before linearized reading' (duration: 1.175645059s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.365255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.188933Z","time spent":"1.176314014s","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-04-20T10:16:58.364473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147610507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:16:58.365397Z","caller":"traceutil/trace.go:171","msg":"trace[312035879] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:587; }","duration":"1.148515438s","start":"2026-04-20T10:16:57.216831Z","end":"2026-04-20T10:16:58.365347Z","steps":["trace[312035879] 'agreement among raft nodes before linearized reading' (duration: 1.147604817s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.365233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.253174Z","time spent":"1.112050572s","remote":"127.0.0.1:47680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:16:58.365591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.087133ms","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-20T10:16:58.365638Z","caller":"traceutil/trace.go:171","msg":"trace[1128229674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"550.171625ms","start":"2026-04-20T10:16:57.815455Z","end":"2026-04-20T10:16:58.365626Z","steps":["trace[1128229674] 'agreement among raft nodes before linearized reading' (duration: 548.734512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:16:58.365592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.216824Z","time spent":"1.148739083s","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-04-20T10:16:58.365681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:16:57.815426Z","time spent":"550.242477ms","remote":"127.0.0.1:39948","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-20T10:17:40.512591Z","caller":"traceutil/trace.go:171","msg":"trace[713665470] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"303.76263ms","start":"2026-04-20T10:17:40.208797Z","end":"2026-04-20T10:17:40.51256Z","steps":["trace[713665470] 'process raft request' (duration: 303.542855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:17:40.512825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:17:40.208754Z","time spent":"303.945874ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:18:03.301341Z","caller":"traceutil/trace.go:171","msg":"trace[1522142053] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1446; }","duration":"105.086493ms","start":"2026-04-20T10:18:03.196221Z","end":"2026-04-20T10:18:03.301308Z","steps":["trace[1522142053] 'read index received' (duration: 104.835997ms)","trace[1522142053] 'applied index is now lower than readState.Index' (duration: 249.496µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:18:03.301488Z","caller":"traceutil/trace.go:171","msg":"trace[747499081] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"151.131738ms","start":"2026-04-20T10:18:03.150301Z","end":"2026-04-20T10:18:03.301433Z","steps":["trace[747499081] 'process raft request' (duration: 150.80821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:03.301591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.342718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:18:03.301627Z","caller":"traceutil/trace.go:171","msg":"trace[617961117] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1406; }","duration":"105.461381ms","start":"2026-04-20T10:18:03.196155Z","end":"2026-04-20T10:18:03.301616Z","steps":["trace[617961117] 'agreement among raft nodes before linearized reading' (duration: 105.362109ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:30.55364Z","caller":"traceutil/trace.go:171","msg":"trace[96059730] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"323.590333ms","start":"2026-04-20T10:18:30.230023Z","end":"2026-04-20T10:18:30.553613Z","steps":["trace[96059730] 'process raft request' (duration: 323.407299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:30.553839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:18:30.229991Z","time spent":"323.752766ms","remote":"127.0.0.1:39948","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-20T10:18:30.599647Z","caller":"traceutil/trace.go:171","msg":"trace[2019721517] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"368.563163ms","start":"2026-04-20T10:18:30.231047Z","end":"2026-04-20T10:18:30.599611Z","steps":["trace[2019721517] 'process raft request' (duration: 368.337527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:30.59985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:18:30.231031Z","time spent":"368.708896ms","remote":"127.0.0.1:39852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3726,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:18:31.031466Z","caller":"traceutil/trace.go:171","msg":"trace[1304847843] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"171.335219ms","start":"2026-04-20T10:18:30.860104Z","end":"2026-04-20T10:18:31.031439Z","steps":["trace[1304847843] 'process raft request' (duration: 171.084163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:31.075555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.541867ms","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-20T10:18:31.075696Z","caller":"traceutil/trace.go:171","msg":"trace[1439274286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1838; }","duration":"142.710051ms","start":"2026-04-20T10:18:30.932964Z","end":"2026-04-20T10:18:31.075674Z","steps":["trace[1439274286] 'agreement among raft nodes before linearized reading' (duration: 142.416024ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:31.075675Z","caller":"traceutil/trace.go:171","msg":"trace[1680085252] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"105.093593ms","start":"2026-04-20T10:18:30.970471Z","end":"2026-04-20T10:18:31.075564Z","steps":["trace[1680085252] 'process raft request' (duration: 104.734524ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:43.261745Z","caller":"traceutil/trace.go:171","msg":"trace[954216588] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"159.964038ms","start":"2026-04-20T10:18:43.101747Z","end":"2026-04-20T10:18:43.261711Z","steps":["trace[954216588] 'process raft request' (duration: 159.751273ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:43.266769Z","caller":"traceutil/trace.go:171","msg":"trace[746772424] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"151.321049ms","start":"2026-04-20T10:18:43.115434Z","end":"2026-04-20T10:18:43.266755Z","steps":["trace[746772424] 'process raft request' (duration: 151.064063ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:43.266745Z","caller":"traceutil/trace.go:171","msg":"trace[1671789026] linearizableReadLoop","detail":"{readStateIndex:2011; appliedIndex:2008; }","duration":"139.638209ms","start":"2026-04-20T10:18:43.127079Z","end":"2026-04-20T10:18:43.266718Z","steps":["trace[1671789026] 'read index received' (duration: 134.479349ms)","trace[1671789026] 'applied index is now lower than readState.Index' (duration: 5.15787ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:18:43.266887Z","caller":"traceutil/trace.go:171","msg":"trace[1668583475] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"148.350989ms","start":"2026-04-20T10:18:43.118483Z","end":"2026-04-20T10:18:43.266834Z","steps":["trace[1668583475] 'process raft request' (duration: 148.197176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:43.267092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.196379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-20T10:18:43.26709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.979386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T10:18:43.267166Z","caller":"traceutil/trace.go:171","msg":"trace[1260058091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1940; }","duration":"139.28737ms","start":"2026-04-20T10:18:43.12784Z","end":"2026-04-20T10:18:43.267127Z","steps":["trace[1260058091] 'agreement among raft nodes before linearized reading' (duration: 139.021414ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:43.267315Z","caller":"traceutil/trace.go:171","msg":"trace[1525914999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1940; }","duration":"140.108629ms","start":"2026-04-20T10:18:43.127028Z","end":"2026-04-20T10:18:43.267137Z","steps":["trace[1525914999] 'agreement among raft nodes before linearized reading' (duration: 139.835303ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:46.868923Z","caller":"traceutil/trace.go:171","msg":"trace[310393442] linearizableReadLoop","detail":"{readStateIndex:2042; appliedIndex:2041; }","duration":"128.343537ms","start":"2026-04-20T10:18:46.740549Z","end":"2026-04-20T10:18:46.868893Z","steps":["trace[310393442] 'read index received' (duration: 128.156683ms)","trace[310393442] 'applied index is now lower than readState.Index' (duration: 185.494µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:18:46.869014Z","caller":"traceutil/trace.go:171","msg":"trace[1579937440] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"232.891164ms","start":"2026-04-20T10:18:46.636086Z","end":"2026-04-20T10:18:46.868978Z","steps":["trace[1579937440] 'process raft request' (duration: 232.600567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:46.869136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.569442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:18:46.869183Z","caller":"traceutil/trace.go:171","msg":"trace[123168122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1970; }","duration":"128.680964ms","start":"2026-04-20T10:18:46.740491Z","end":"2026-04-20T10:18:46.869171Z","steps":["trace[123168122] 'agreement among raft nodes before linearized reading' (duration: 128.582872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:05.195501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.329126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:19:05.195698Z","caller":"traceutil/trace.go:171","msg":"trace[157484782] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"181.445145ms","start":"2026-04-20T10:19:05.01423Z","end":"2026-04-20T10:19:05.195675Z","steps":["trace[157484782] 'process raft request' (duration: 78.845173ms)","trace[157484782] 'compare' (duration: 101.852695ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:07.907159Z","caller":"traceutil/trace.go:171","msg":"trace[786997511] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"112.098301ms","start":"2026-04-20T10:19:07.79503Z","end":"2026-04-20T10:19:07.907129Z","steps":["trace[786997511] 'process raft request' (duration: 49.226448ms)","trace[786997511] 'compare' (duration: 62.638108ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:13.837312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.289614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:13.837423Z","caller":"traceutil/trace.go:171","msg":"trace[1787392447] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2120; }","duration":"111.456347ms","start":"2026-04-20T10:19:13.72594Z","end":"2026-04-20T10:19:13.837397Z","steps":["trace[1787392447] 'agreement among raft nodes before linearized reading' (duration: 111.14262ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:20.198248Z","caller":"traceutil/trace.go:171","msg":"trace[263797183] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2233; }","duration":"135.77808ms","start":"2026-04-20T10:19:20.062433Z","end":"2026-04-20T10:19:20.198211Z","steps":["trace[263797183] 'read index received' (duration: 109.531303ms)","trace[263797183] 'applied index is now lower than readState.Index' (duration: 26.245687ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:20.198307Z","caller":"traceutil/trace.go:171","msg":"trace[630034524] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"152.782102ms","start":"2026-04-20T10:19:20.045498Z","end":"2026-04-20T10:19:20.19828Z","steps":["trace[630034524] 'process raft request' (duration: 126.616927ms)","trace[630034524] 'compare' (duration: 25.96377ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:20.198522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.064886ms","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-20T10:19:20.198577Z","caller":"traceutil/trace.go:171","msg":"trace[681028638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2155; }","duration":"136.175969ms","start":"2026-04-20T10:19:20.062386Z","end":"2026-04-20T10:19:20.198562Z","steps":["trace[681028638] 'agreement among raft nodes before linearized reading' (duration: 135.962454ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:21.644238Z","caller":"traceutil/trace.go:171","msg":"trace[1265257003] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"185.665663ms","start":"2026-04-20T10:19:21.458549Z","end":"2026-04-20T10:19:21.644215Z","steps":["trace[1265257003] 'process raft request' (duration: 185.504029ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:21.857845Z","caller":"traceutil/trace.go:171","msg":"trace[1246553313] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"252.128849ms","start":"2026-04-20T10:19:21.605673Z","end":"2026-04-20T10:19:21.857802Z","steps":["trace[1246553313] 'process raft request' (duration: 252.031297ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:21.857882Z","caller":"traceutil/trace.go:171","msg":"trace[392157907] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"357.673109ms","start":"2026-04-20T10:19:21.500141Z","end":"2026-04-20T10:19:21.857815Z","steps":["trace[392157907] 'process raft request' (duration: 357.354322ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:21.858011Z","caller":"traceutil/trace.go:171","msg":"trace[1845879182] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2236; }","duration":"188.663111ms","start":"2026-04-20T10:19:21.669309Z","end":"2026-04-20T10:19:21.857972Z","steps":["trace[1845879182] 'read index received' (duration: 188.048356ms)","trace[1845879182] 'applied index is now lower than readState.Index' (duration: 612.705µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:21.858138Z","caller":"traceutil/trace.go:171","msg":"trace[1955300425] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"241.119683ms","start":"2026-04-20T10:19:21.617006Z","end":"2026-04-20T10:19:21.858126Z","steps":["trace[1955300425] 'process raft request' (duration: 240.745295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:21.858145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:21.500102Z","time spent":"357.909835ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:19:21.858357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.04401ms","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-20T10:19:21.858404Z","caller":"traceutil/trace.go:171","msg":"trace[64869980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2160; }","duration":"189.177463ms","start":"2026-04-20T10:19:21.669216Z","end":"2026-04-20T10:19:21.858393Z","steps":["trace[64869980] 'agreement among raft nodes before linearized reading' (duration: 188.956848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:22.190877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.12513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:19:22.191076Z","caller":"traceutil/trace.go:171","msg":"trace[1717749654] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"326.814445ms","start":"2026-04-20T10:19:21.864238Z","end":"2026-04-20T10:19:22.191052Z","steps":["trace[1717749654] 'process raft request' (duration: 326.750414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:22.191155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:21.864205Z","time spent":"326.917678ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:19:22.191229Z","caller":"traceutil/trace.go:171","msg":"trace[427599209] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2239; }","duration":"272.362296ms","start":"2026-04-20T10:19:21.918831Z","end":"2026-04-20T10:19:22.191194Z","steps":["trace[427599209] 'read index received' (duration: 102.912799ms)","trace[427599209] 'applied index is now lower than readState.Index' (duration: 169.448037ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:22.191325Z","caller":"traceutil/trace.go:171","msg":"trace[1631854667] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"327.610264ms","start":"2026-04-20T10:19:21.863655Z","end":"2026-04-20T10:19:22.191265Z","steps":["trace[1631854667] 'process raft request' (duration: 158.000403ms)","trace[1631854667] 'compare' (duration: 169.001907ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:22.191339Z","caller":"traceutil/trace.go:171","msg":"trace[1753501837] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"225.940103ms","start":"2026-04-20T10:19:21.965342Z","end":"2026-04-20T10:19:22.191282Z","steps":["trace[1753501837] 'process raft request' (duration: 225.689218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:22.191402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.556862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:19:22.191474Z","caller":"traceutil/trace.go:171","msg":"trace[1324356274] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2163; }","duration":"272.687264ms","start":"2026-04-20T10:19:21.918765Z","end":"2026-04-20T10:19:22.191452Z","steps":["trace[1324356274] 'agreement among raft nodes before linearized reading' (duration: 272.546301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:22.191539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:21.863633Z","time spent":"327.789568ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:19:22.191572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.270827ms","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-20T10:19:22.191642Z","caller":"traceutil/trace.go:171","msg":"trace[1735144944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2163; }","duration":"196.39424ms","start":"2026-04-20T10:19:21.995234Z","end":"2026-04-20T10:19:22.191629Z","steps":["trace[1735144944] 'agreement among raft nodes before linearized reading' (duration: 196.174705ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:34.307544Z","caller":"traceutil/trace.go:171","msg":"trace[661770191] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"106.774354ms","start":"2026-04-20T10:20:34.200734Z","end":"2026-04-20T10:20:34.307508Z","steps":["trace[661770191] 'process raft request' (duration: 105.586147ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:40.447872Z","caller":"traceutil/trace.go:171","msg":"trace[143196699] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"270.393597ms","start":"2026-04-20T10:20:40.177441Z","end":"2026-04-20T10:20:40.447834Z","steps":["trace[143196699] 'process raft request' (duration: 270.190882ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:40.458425Z","caller":"traceutil/trace.go:171","msg":"trace[1086692735] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"132.392918ms","start":"2026-04-20T10:20:40.326016Z","end":"2026-04-20T10:20:40.458409Z","steps":["trace[1086692735] 'process raft request' (duration: 132.277315ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:01.72031Z","caller":"traceutil/trace.go:171","msg":"trace[1228896200] transaction","detail":"{read_only:false; response_revision:2883; number_of_response:1; }","duration":"120.425695ms","start":"2026-04-20T10:21:01.599837Z","end":"2026-04-20T10:21:01.720263Z","steps":["trace[1228896200] 'process raft request' (duration: 120.072167ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:01.847107Z","caller":"traceutil/trace.go:171","msg":"trace[57109064] linearizableReadLoop","detail":"{readStateIndex:2993; appliedIndex:2992; }","duration":"106.933172ms","start":"2026-04-20T10:21:01.740139Z","end":"2026-04-20T10:21:01.847072Z","steps":["trace[57109064] 'read index received' (duration: 74.657642ms)","trace[57109064] 'applied index is now lower than readState.Index' (duration: 32.27425ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:21:01.847301Z","caller":"traceutil/trace.go:171","msg":"trace[1720737478] transaction","detail":"{read_only:false; response_revision:2884; number_of_response:1; }","duration":"123.939227ms","start":"2026-04-20T10:21:01.723301Z","end":"2026-04-20T10:21:01.84724Z","steps":["trace[1720737478] 'process raft request' (duration: 91.473993ms)","trace[1720737478] 'compare' (duration: 32.124056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:21:01.847352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.185168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:21:01.847435Z","caller":"traceutil/trace.go:171","msg":"trace[1493119799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2884; }","duration":"107.350222ms","start":"2026-04-20T10:21:01.740069Z","end":"2026-04-20T10:21:01.847419Z","steps":["trace[1493119799] 'agreement among raft nodes before linearized reading' (duration: 107.160687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:11.156287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.514422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:21:11.1564Z","caller":"traceutil/trace.go:171","msg":"trace[1495397079] linearizableReadLoop","detail":"{readStateIndex:3046; appliedIndex:3045; }","duration":"245.669732ms","start":"2026-04-20T10:21:10.910713Z","end":"2026-04-20T10:21:11.156382Z","steps":["trace[1495397079] 'read index received' (duration: 26.875693ms)","trace[1495397079] 'applied index is now lower than readState.Index' (duration: 218.792898ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:21:11.156515Z","caller":"traceutil/trace.go:171","msg":"trace[1144877145] transaction","detail":"{read_only:false; response_revision:2935; number_of_response:1; }","duration":"250.855533ms","start":"2026-04-20T10:21:10.905623Z","end":"2026-04-20T10:21:11.156479Z","steps":["trace[1144877145] 'process raft request' (duration: 32.044904ms)","trace[1144877145] 'compare' (duration: 218.378289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:21:11.156614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.906298ms","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-20T10:21:11.156653Z","caller":"traceutil/trace.go:171","msg":"trace[1236683227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2935; }","duration":"245.970659ms","start":"2026-04-20T10:21:10.910672Z","end":"2026-04-20T10:21:11.156643Z","steps":["trace[1236683227] 'agreement among raft nodes before linearized reading' (duration: 245.806435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:11.156733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.884608ms","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-20T10:21:11.15681Z","caller":"traceutil/trace.go:171","msg":"trace[1393586036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2935; }","duration":"240.012731ms","start":"2026-04-20T10:21:10.916778Z","end":"2026-04-20T10:21:11.156791Z","steps":["trace[1393586036] 'agreement among raft nodes before linearized reading' (duration: 239.759695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:11.157121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.475552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T10:21:11.157318Z","caller":"traceutil/trace.go:171","msg":"trace[511726730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2935; }","duration":"200.642247ms","start":"2026-04-20T10:21:10.956563Z","end":"2026-04-20T10:21:11.157206Z","steps":["trace[511726730] 'agreement among raft nodes before linearized reading' (duration: 200.164065ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:11.341377Z","caller":"traceutil/trace.go:171","msg":"trace[1463312679] transaction","detail":"{read_only:false; response_revision:2937; number_of_response:1; }","duration":"179.846434ms","start":"2026-04-20T10:21:11.161489Z","end":"2026-04-20T10:21:11.341336Z","steps":["trace[1463312679] 'process raft request' (duration: 179.521716ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:11.34149Z","caller":"traceutil/trace.go:171","msg":"trace[1388908652] transaction","detail":"{read_only:false; response_revision:2938; number_of_response:1; }","duration":"179.599029ms","start":"2026-04-20T10:21:11.161859Z","end":"2026-04-20T10:21:11.341458Z","steps":["trace[1388908652] 'process raft request' (duration: 179.353193ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:14.683741Z","caller":"traceutil/trace.go:171","msg":"trace[931004361] transaction","detail":"{read_only:false; response_revision:2949; number_of_response:1; }","duration":"190.00977ms","start":"2026-04-20T10:21:14.493704Z","end":"2026-04-20T10:21:14.683714Z","steps":["trace[931004361] 'process raft request' (duration: 189.810156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:15.226798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.779837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:21:15.227071Z","caller":"traceutil/trace.go:171","msg":"trace[627324549] linearizableReadLoop","detail":"{readStateIndex:3063; appliedIndex:3060; }","duration":"488.381425ms","start":"2026-04-20T10:21:14.738672Z","end":"2026-04-20T10:21:15.227053Z","steps":["trace[627324549] 'read index received' (duration: 51.245499ms)","trace[627324549] 'applied index is now lower than readState.Index' (duration: 437.134295ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:21:15.227235Z","caller":"traceutil/trace.go:171","msg":"trace[212933515] transaction","detail":"{read_only:false; response_revision:2951; number_of_response:1; }","duration":"583.098763ms","start":"2026-04-20T10:21:14.644109Z","end":"2026-04-20T10:21:15.227208Z","steps":["trace[212933515] 'process raft request' (duration: 582.805606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:15.227561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:14.644076Z","time spent":"583.40636ms","remote":"127.0.0.1:39948","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-20T10:21:15.227695Z","caller":"traceutil/trace.go:171","msg":"trace[1542545659] transaction","detail":"{read_only:false; response_revision:2950; number_of_response:1; }","duration":"617.45373ms","start":"2026-04-20T10:21:14.610234Z","end":"2026-04-20T10:21:15.227687Z","steps":["trace[1542545659] 'process raft request' (duration: 179.67684ms)","trace[1542545659] 'compare' (duration: 436.412509ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:21:15.227729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:14.610203Z","time spent":"617.509392ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:21:15.227794Z","caller":"traceutil/trace.go:171","msg":"trace[237403793] transaction","detail":"{read_only:false; response_revision:2952; number_of_response:1; }","duration":"523.679174ms","start":"2026-04-20T10:21:14.704077Z","end":"2026-04-20T10:21:15.227757Z","steps":["trace[237403793] 'process raft request' (duration: 522.937377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:15.227918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:14.704043Z","time spent":"523.811167ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:21:15.228442Z","caller":"traceutil/trace.go:171","msg":"trace[143335068] transaction","detail":"{read_only:false; response_revision:2953; number_of_response:1; }","duration":"334.200276ms","start":"2026-04-20T10:21:14.89423Z","end":"2026-04-20T10:21:15.22843Z","steps":["trace[143335068] 'process raft request' (duration: 334.015882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:15.228515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:14.894217Z","time spent":"334.262608ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:21:15.228761Z","caller":"traceutil/trace.go:171","msg":"trace[1784848180] transaction","detail":"{read_only:false; response_revision:2954; number_of_response:1; }","duration":"334.33067ms","start":"2026-04-20T10:21:14.89438Z","end":"2026-04-20T10:21:15.22871Z","steps":["trace[1784848180] 'process raft request' (duration: 334.013253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:15.22905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:14.894349Z","time spent":"334.575955ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:21:15.229194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.082964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18a80975d81f4f61\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-20T10:21:15.229302Z","caller":"traceutil/trace.go:171","msg":"trace[572760611] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18a80975d81f4f61; range_end:; response_count:1; response_revision:2952; }","duration":"490.663108ms","start":"2026-04-20T10:21:14.738613Z","end":"2026-04-20T10:21:15.229276Z","steps":["trace[572760611] 'agreement among raft nodes before linearized reading' (duration: 488.834306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:15.229369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:14.738587Z","time spent":"490.76552ms","remote":"127.0.0.1:39758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":787,"request content":"key:\"/registry/events/openstack/valkey-node-0.18a80975d81f4f61\" "} {"level":"warn","ts":"2026-04-20T10:21:15.233428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.116117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:21:15.233472Z","caller":"traceutil/trace.go:171","msg":"trace[1891585228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2955; }","duration":"494.167209ms","start":"2026-04-20T10:21:14.739291Z","end":"2026-04-20T10:21:15.233458Z","steps":["trace[1891585228] 'agreement among raft nodes before linearized reading' (duration: 494.101817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:15.233498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:14.739285Z","time spent":"494.20711ms","remote":"127.0.0.1:39716","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-20T10:21:15.233923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.261743ms","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-20T10:21:15.233954Z","caller":"traceutil/trace.go:171","msg":"trace[1563560866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2955; }","duration":"351.320713ms","start":"2026-04-20T10:21:14.882626Z","end":"2026-04-20T10:21:15.233947Z","steps":["trace[1563560866] 'agreement among raft nodes before linearized reading' (duration: 351.237551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:15.233977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:14.882608Z","time spent":"351.363315ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T10:21:15.234401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.711971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:21:15.234434Z","caller":"traceutil/trace.go:171","msg":"trace[1527025237] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2955; }","duration":"391.798832ms","start":"2026-04-20T10:21:14.842628Z","end":"2026-04-20T10:21:15.234427Z","steps":["trace[1527025237] 'agreement among raft nodes before linearized reading' (duration: 391.465755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:15.234459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:14.842602Z","time spent":"391.849444ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:25:20.195227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.517174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4qggz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T10:25:20.195364Z","caller":"traceutil/trace.go:171","msg":"trace[1583852468] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4qggz; range_end:; response_count:1; response_revision:4885; }","duration":"148.721409ms","start":"2026-04-20T10:25:20.046609Z","end":"2026-04-20T10:25:20.19533Z","steps":["trace[1583852468] 'range keys from in-memory index tree' (duration: 148.262138ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:21.607144Z","caller":"traceutil/trace.go:171","msg":"trace[1000092535] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"114.555614ms","start":"2026-04-20T10:25:21.49255Z","end":"2026-04-20T10:25:21.607106Z","steps":["trace[1000092535] 'process raft request' (duration: 114.39726ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:26.868268Z","caller":"traceutil/trace.go:171","msg":"trace[1414183782] linearizableReadLoop","detail":"{readStateIndex:5277; appliedIndex:5276; }","duration":"131.63656ms","start":"2026-04-20T10:25:26.736599Z","end":"2026-04-20T10:25:26.868236Z","steps":["trace[1414183782] 'read index received' (duration: 131.334633ms)","trace[1414183782] 'applied index is now lower than readState.Index' (duration: 300.807µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:25:26.868768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.137572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:25:26.868764Z","caller":"traceutil/trace.go:171","msg":"trace[850858154] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"136.083884ms","start":"2026-04-20T10:25:26.732641Z","end":"2026-04-20T10:25:26.868725Z","steps":["trace[850858154] 'process raft request' (duration: 135.395398ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:26.868836Z","caller":"traceutil/trace.go:171","msg":"trace[541625640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5083; }","duration":"132.257765ms","start":"2026-04-20T10:25:26.736567Z","end":"2026-04-20T10:25:26.868825Z","steps":["trace[541625640] 'agreement among raft nodes before linearized reading' (duration: 131.968378ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:30.702614Z","caller":"traceutil/trace.go:171","msg":"trace[505076229] linearizableReadLoop","detail":"{readStateIndex:5409; appliedIndex:5408; }","duration":"349.481755ms","start":"2026-04-20T10:25:30.353108Z","end":"2026-04-20T10:25:30.70259Z","steps":["trace[505076229] 'read index received' (duration: 349.377563ms)","trace[505076229] 'applied index is now lower than readState.Index' (duration: 103.422µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:25:30.702724Z","caller":"traceutil/trace.go:171","msg":"trace[1408266733] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"379.489454ms","start":"2026-04-20T10:25:30.323211Z","end":"2026-04-20T10:25:30.702701Z","steps":["trace[1408266733] 'process raft request' (duration: 379.181067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:30.702873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:25:30.323164Z","time spent":"379.618137ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:25:30.703208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.09488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719496"} {"level":"info","ts":"2026-04-20T10:25:30.703283Z","caller":"traceutil/trace.go:171","msg":"trace[1177766728] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5212; }","duration":"350.204113ms","start":"2026-04-20T10:25:30.353059Z","end":"2026-04-20T10:25:30.703263Z","steps":["trace[1177766728] 'agreement among raft nodes before linearized reading' (duration: 349.684851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:30.703344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:25:30.353039Z","time spent":"350.285274ms","remote":"127.0.0.1:39776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":24,"response size":719518,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-04-20T10:25:30.728079Z","caller":"traceutil/trace.go:171","msg":"trace[1491588093] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"304.060751ms","start":"2026-04-20T10:25:30.423996Z","end":"2026-04-20T10:25:30.728057Z","steps":["trace[1491588093] 'process raft request' (duration: 304.004349ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:30.728126Z","caller":"traceutil/trace.go:171","msg":"trace[1815213382] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"306.772683ms","start":"2026-04-20T10:25:30.421317Z","end":"2026-04-20T10:25:30.72809Z","steps":["trace[1815213382] 'process raft request' (duration: 306.570309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:30.728214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:25:30.423984Z","time spent":"304.160873ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:25:30.728244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:25:30.421288Z","time spent":"306.884786ms","remote":"127.0.0.1:39948","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-20T10:25:30.728551Z","caller":"traceutil/trace.go:171","msg":"trace[929197690] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"302.259039ms","start":"2026-04-20T10:25:30.426283Z","end":"2026-04-20T10:25:30.728542Z","steps":["trace[929197690] 'process raft request' (duration: 301.746797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:30.728599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:25:30.426251Z","time spent":"302.325771ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:25:30.728615Z","caller":"traceutil/trace.go:171","msg":"trace[2138624519] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"211.872897ms","start":"2026-04-20T10:25:30.516729Z","end":"2026-04-20T10:25:30.728602Z","steps":["trace[2138624519] 'process raft request' (duration: 211.714914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:30.728743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.461599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-df045128-8552-4dd5-b435-e72f5c7f6d2d\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-20T10:25:30.728767Z","caller":"traceutil/trace.go:171","msg":"trace[1703948433] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-df045128-8552-4dd5-b435-e72f5c7f6d2d; range_end:; response_count:1; response_revision:5216; }","duration":"193.50957ms","start":"2026-04-20T10:25:30.535251Z","end":"2026-04-20T10:25:30.72876Z","steps":["trace[1703948433] 'agreement among raft nodes before linearized reading' (duration: 193.406088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:30.728853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.380732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"info","ts":"2026-04-20T10:25:30.728938Z","caller":"traceutil/trace.go:171","msg":"trace[1493614930] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5216; }","duration":"367.497735ms","start":"2026-04-20T10:25:30.361421Z","end":"2026-04-20T10:25:30.728919Z","steps":["trace[1493614930] 'agreement among raft nodes before linearized reading' (duration: 367.177887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:30.728985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:25:30.361411Z","time spent":"367.564026ms","remote":"127.0.0.1:51118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":413152,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-04-20T10:25:32.919028Z","caller":"traceutil/trace.go:171","msg":"trace[34714295] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"185.142755ms","start":"2026-04-20T10:25:32.733842Z","end":"2026-04-20T10:25:32.918985Z","steps":["trace[34714295] 'process raft request' (duration: 184.90804ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:33.000717Z","caller":"traceutil/trace.go:171","msg":"trace[1848573082] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"266.725603ms","start":"2026-04-20T10:25:32.733962Z","end":"2026-04-20T10:25:33.000687Z","steps":["trace[1848573082] 'process raft request' (duration: 241.831943ms)","trace[1848573082] 'compare' (duration: 24.712585ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:25:33.000963Z","caller":"traceutil/trace.go:171","msg":"trace[1967538238] linearizableReadLoop","detail":"{readStateIndex:5454; appliedIndex:5450; }","duration":"119.51412ms","start":"2026-04-20T10:25:32.881428Z","end":"2026-04-20T10:25:33.000942Z","steps":["trace[1967538238] 'read index received' (duration: 37.39745ms)","trace[1967538238] 'applied index is now lower than readState.Index' (duration: 82.1154ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:25:33.000978Z","caller":"traceutil/trace.go:171","msg":"trace[1060312240] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"266.221011ms","start":"2026-04-20T10:25:32.734734Z","end":"2026-04-20T10:25:33.000955Z","steps":["trace[1060312240] 'process raft request' (duration: 265.913814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:33.001332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.167105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-df045128-8552-4dd5-b435-e72f5c7f6d2d\" ","response":"range_response_count:1 size:3904"} {"level":"info","ts":"2026-04-20T10:25:33.001405Z","caller":"traceutil/trace.go:171","msg":"trace[2033967736] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-df045128-8552-4dd5-b435-e72f5c7f6d2d; range_end:; response_count:1; response_revision:5255; }","duration":"114.316638ms","start":"2026-04-20T10:25:32.887069Z","end":"2026-04-20T10:25:33.001386Z","steps":["trace[2033967736] 'agreement among raft nodes before linearized reading' (duration: 114.103343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:33.001395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.914829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:25:33.001424Z","caller":"traceutil/trace.go:171","msg":"trace[1156204414] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"260.080448ms","start":"2026-04-20T10:25:32.741326Z","end":"2026-04-20T10:25:33.001406Z","steps":["trace[1156204414] 'process raft request' (duration: 259.534056ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:33.001501Z","caller":"traceutil/trace.go:171","msg":"trace[1453482149] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5255; }","duration":"120.104353ms","start":"2026-04-20T10:25:32.881372Z","end":"2026-04-20T10:25:33.001477Z","steps":["trace[1453482149] 'agreement among raft nodes before linearized reading' (duration: 119.879458ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:33.160928Z","caller":"traceutil/trace.go:171","msg":"trace[1275638882] linearizableReadLoop","detail":"{readStateIndex:5457; appliedIndex:5456; }","duration":"107.182952ms","start":"2026-04-20T10:25:33.053721Z","end":"2026-04-20T10:25:33.160904Z","steps":["trace[1275638882] 'read index received' (duration: 91.907057ms)","trace[1275638882] 'applied index is now lower than readState.Index' (duration: 15.274615ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:25:33.161036Z","caller":"traceutil/trace.go:171","msg":"trace[1604870270] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"110.796617ms","start":"2026-04-20T10:25:33.050204Z","end":"2026-04-20T10:25:33.161001Z","steps":["trace[1604870270] 'process raft request' (duration: 95.517291ms)","trace[1604870270] 'compare' (duration: 15.04296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:25:33.161151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.420607ms","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-20T10:25:33.16119Z","caller":"traceutil/trace.go:171","msg":"trace[1259568337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5258; }","duration":"107.50373ms","start":"2026-04-20T10:25:33.053677Z","end":"2026-04-20T10:25:33.161181Z","steps":["trace[1259568337] 'agreement among raft nodes before linearized reading' (duration: 107.336526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:33.161229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.63012ms","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-20T10:25:33.161292Z","caller":"traceutil/trace.go:171","msg":"trace[617543236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5258; }","duration":"103.723572ms","start":"2026-04-20T10:25:33.057556Z","end":"2026-04-20T10:25:33.161279Z","steps":["trace[617543236] 'agreement among raft nodes before linearized reading' (duration: 103.513917ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:42.8025Z","caller":"traceutil/trace.go:171","msg":"trace[1983174378] linearizableReadLoop","detail":"{readStateIndex:5644; appliedIndex:5642; }","duration":"100.329673ms","start":"2026-04-20T10:25:42.702142Z","end":"2026-04-20T10:25:42.802471Z","steps":["trace[1983174378] 'read index received' (duration: 25.699308ms)","trace[1983174378] 'applied index is now lower than readState.Index' (duration: 74.629225ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:25:42.802683Z","caller":"traceutil/trace.go:171","msg":"trace[1414031973] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"101.657424ms","start":"2026-04-20T10:25:42.700987Z","end":"2026-04-20T10:25:42.802645Z","steps":["trace[1414031973] 'process raft request' (duration: 26.811424ms)","trace[1414031973] 'compare' (duration: 74.309338ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:25:42.802801Z","caller":"traceutil/trace.go:171","msg":"trace[580491515] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"101.232724ms","start":"2026-04-20T10:25:42.701537Z","end":"2026-04-20T10:25:42.80277Z","steps":["trace[580491515] 'process raft request' (duration: 100.765313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:42.802827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.676782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17171"} {"level":"info","ts":"2026-04-20T10:25:42.803002Z","caller":"traceutil/trace.go:171","msg":"trace[1565959414] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5440; }","duration":"100.871216ms","start":"2026-04-20T10:25:42.702071Z","end":"2026-04-20T10:25:42.802942Z","steps":["trace[1565959414] 'agreement among raft nodes before linearized reading' (duration: 100.575759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:43.460401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.158033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:25:43.460533Z","caller":"traceutil/trace.go:171","msg":"trace[1264781395] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"408.019228ms","start":"2026-04-20T10:25:43.052493Z","end":"2026-04-20T10:25:43.460512Z","steps":["trace[1264781395] 'process raft request' (duration: 66.64891ms)","trace[1264781395] 'compare' (duration: 341.04294ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:25:43.460597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:25:43.052462Z","time spent":"408.104009ms","remote":"127.0.0.1:39948","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-20T10:25:43.462827Z","caller":"traceutil/trace.go:171","msg":"trace[2052119396] linearizableReadLoop","detail":"{readStateIndex:5658; appliedIndex:5655; }","duration":"391.913994ms","start":"2026-04-20T10:25:43.070866Z","end":"2026-04-20T10:25:43.46278Z","steps":["trace[2052119396] 'read index received' (duration: 48.356815ms)","trace[2052119396] 'applied index is now lower than readState.Index' (duration: 343.555949ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:25:43.462975Z","caller":"traceutil/trace.go:171","msg":"trace[1558213005] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"398.381214ms","start":"2026-04-20T10:25:43.064562Z","end":"2026-04-20T10:25:43.462943Z","steps":["trace[1558213005] 'process raft request' (duration: 397.988214ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:43.46301Z","caller":"traceutil/trace.go:171","msg":"trace[1351615158] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"396.724745ms","start":"2026-04-20T10:25:43.066246Z","end":"2026-04-20T10:25:43.462971Z","steps":["trace[1351615158] 'process raft request' (duration: 396.474069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:43.463109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.65464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-20T10:25:43.463124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:25:43.066221Z","time spent":"396.846079ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:25:43.463197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.500595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T10:25:43.463126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:25:43.064536Z","time spent":"398.504187ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:25:43.463143Z","caller":"traceutil/trace.go:171","msg":"trace[1877761695] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5453; }","duration":"364.724731ms","start":"2026-04-20T10:25:43.098408Z","end":"2026-04-20T10:25:43.463133Z","steps":["trace[1877761695] 'agreement among raft nodes before linearized reading' (duration: 364.589588ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:43.463275Z","caller":"traceutil/trace.go:171","msg":"trace[964795352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5453; }","duration":"244.602098ms","start":"2026-04-20T10:25:43.218657Z","end":"2026-04-20T10:25:43.463259Z","steps":["trace[964795352] 'agreement among raft nodes before linearized reading' (duration: 244.413234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:43.463299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.904385ms","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-20T10:25:43.463368Z","caller":"traceutil/trace.go:171","msg":"trace[607585794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5453; }","duration":"245.023368ms","start":"2026-04-20T10:25:43.218325Z","end":"2026-04-20T10:25:43.463349Z","steps":["trace[607585794] 'agreement among raft nodes before linearized reading' (duration: 244.771132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:43.463357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:25:43.098389Z","time spent":"364.915856ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T10:25:43.46339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.515245ms","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-20T10:25:43.463429Z","caller":"traceutil/trace.go:171","msg":"trace[1771834740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5453; }","duration":"259.584727ms","start":"2026-04-20T10:25:43.203834Z","end":"2026-04-20T10:25:43.463418Z","steps":["trace[1771834740] 'agreement among raft nodes before linearized reading' (duration: 259.326341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:43.463432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.549118ms","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-20T10:25:43.463528Z","caller":"traceutil/trace.go:171","msg":"trace[1470641495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5453; }","duration":"392.700662ms","start":"2026-04-20T10:25:43.070807Z","end":"2026-04-20T10:25:43.463507Z","steps":["trace[1470641495] 'agreement among raft nodes before linearized reading' (duration: 392.323423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:43.463607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:25:43.070761Z","time spent":"392.816114ms","remote":"127.0.0.1:39948","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-20T10:25:51.748454Z","caller":"traceutil/trace.go:171","msg":"trace[1803260653] linearizableReadLoop","detail":"{readStateIndex:5828; appliedIndex:5825; }","duration":"205.019258ms","start":"2026-04-20T10:25:51.543402Z","end":"2026-04-20T10:25:51.748421Z","steps":["trace[1803260653] 'read index received' (duration: 145.374351ms)","trace[1803260653] 'applied index is now lower than readState.Index' (duration: 59.643327ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:25:51.748517Z","caller":"traceutil/trace.go:171","msg":"trace[2037648024] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"217.977049ms","start":"2026-04-20T10:25:51.530504Z","end":"2026-04-20T10:25:51.748481Z","steps":["trace[2037648024] 'process raft request' (duration: 217.799365ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:51.748562Z","caller":"traceutil/trace.go:171","msg":"trace[1376220278] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"219.442643ms","start":"2026-04-20T10:25:51.52907Z","end":"2026-04-20T10:25:51.748513Z","steps":["trace[1376220278] 'process raft request' (duration: 159.635542ms)","trace[1376220278] 'compare' (duration: 59.474043ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:25:51.74863Z","caller":"traceutil/trace.go:171","msg":"trace[25947966] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"214.073278ms","start":"2026-04-20T10:25:51.534541Z","end":"2026-04-20T10:25:51.748614Z","steps":["trace[25947966] 'process raft request' (duration: 213.806762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:51.748824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.394566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T10:25:51.748886Z","caller":"traceutil/trace.go:171","msg":"trace[84862184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5619; }","duration":"205.529699ms","start":"2026-04-20T10:25:51.543341Z","end":"2026-04-20T10:25:51.748871Z","steps":["trace[84862184] 'agreement among raft nodes before linearized reading' (duration: 205.321104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:51.748892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.017198ms","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-20T10:25:51.748946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.393572ms","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-20T10:25:51.749021Z","caller":"traceutil/trace.go:171","msg":"trace[1185644165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5619; }","duration":"201.512885ms","start":"2026-04-20T10:25:51.547491Z","end":"2026-04-20T10:25:51.749004Z","steps":["trace[1185644165] 'agreement among raft nodes before linearized reading' (duration: 201.330821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:51.749015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.631228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-20T10:25:51.748938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.388963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T10:25:51.749117Z","caller":"traceutil/trace.go:171","msg":"trace[457780846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5619; }","duration":"205.165181ms","start":"2026-04-20T10:25:51.543794Z","end":"2026-04-20T10:25:51.748959Z","steps":["trace[457780846] 'agreement among raft nodes before linearized reading' (duration: 204.937666ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:51.749139Z","caller":"traceutil/trace.go:171","msg":"trace[1357884057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5619; }","duration":"204.613058ms","start":"2026-04-20T10:25:51.544507Z","end":"2026-04-20T10:25:51.74912Z","steps":["trace[1357884057] 'agreement among raft nodes before linearized reading' (duration: 204.316271ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:51.749076Z","caller":"traceutil/trace.go:171","msg":"trace[1159208826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5619; }","duration":"204.725741ms","start":"2026-04-20T10:25:51.544332Z","end":"2026-04-20T10:25:51.749058Z","steps":["trace[1159208826] 'agreement among raft nodes before linearized reading' (duration: 204.587447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:25:54.057449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.457343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-20T10:25:54.057543Z","caller":"traceutil/trace.go:171","msg":"trace[755131977] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5653; }","duration":"136.623157ms","start":"2026-04-20T10:25:53.9209Z","end":"2026-04-20T10:25:54.057523Z","steps":["trace[755131977] 'range keys from in-memory index tree' (duration: 136.259358ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:26:03.905343Z","caller":"traceutil/trace.go:171","msg":"trace[1776089315] transaction","detail":"{read_only:false; response_revision:5776; number_of_response:1; }","duration":"106.419475ms","start":"2026-04-20T10:26:03.798872Z","end":"2026-04-20T10:26:03.905291Z","steps":["trace[1776089315] 'process raft request' (duration: 106.054966ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:26:03.906734Z","caller":"traceutil/trace.go:171","msg":"trace[562098134] transaction","detail":"{read_only:false; response_revision:5777; number_of_response:1; }","duration":"102.551655ms","start":"2026-04-20T10:26:03.802705Z","end":"2026-04-20T10:26:03.905256Z","steps":["trace[562098134] 'process raft request' (duration: 102.416282ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:26:04.023275Z","caller":"traceutil/trace.go:171","msg":"trace[747921227] transaction","detail":"{read_only:false; response_revision:5782; number_of_response:1; }","duration":"111.549424ms","start":"2026-04-20T10:26:03.911686Z","end":"2026-04-20T10:26:04.023235Z","steps":["trace[747921227] 'process raft request' (duration: 111.505043ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:26:04.023398Z","caller":"traceutil/trace.go:171","msg":"trace[348126646] transaction","detail":"{read_only:false; response_revision:5780; number_of_response:1; }","duration":"111.965744ms","start":"2026-04-20T10:26:03.911379Z","end":"2026-04-20T10:26:04.023345Z","steps":["trace[348126646] 'process raft request' (duration: 111.735229ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:26:04.023432Z","caller":"traceutil/trace.go:171","msg":"trace[470550685] transaction","detail":"{read_only:false; response_revision:5781; number_of_response:1; }","duration":"111.914952ms","start":"2026-04-20T10:26:03.911491Z","end":"2026-04-20T10:26:04.023406Z","steps":["trace[470550685] 'process raft request' (duration: 111.663806ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:26:04.023618Z","caller":"traceutil/trace.go:171","msg":"trace[112551151] transaction","detail":"{read_only:false; response_revision:5778; number_of_response:1; }","duration":"112.665429ms","start":"2026-04-20T10:26:03.910916Z","end":"2026-04-20T10:26:04.023581Z","steps":["trace[112551151] 'process raft request' (duration: 29.434284ms)","trace[112551151] 'compare' (duration: 82.495068ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:26:04.023351Z","caller":"traceutil/trace.go:171","msg":"trace[30179214] transaction","detail":"{read_only:false; response_revision:5779; number_of_response:1; }","duration":"111.984154ms","start":"2026-04-20T10:26:03.911316Z","end":"2026-04-20T10:26:04.0233Z","steps":["trace[30179214] 'process raft request' (duration: 111.742538ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:26:16.614048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2962} {"level":"info","ts":"2026-04-20T10:26:16.69828Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2962,"took":"82.640479ms","hash":259860223,"current-db-size-bytes":56061952,"current-db-size":"56 MB","current-db-size-in-use-bytes":31858688,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-20T10:26:16.698354Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":259860223,"revision":2962,"compact-revision":-1} {"level":"info","ts":"2026-04-20T10:26:32.740877Z","caller":"traceutil/trace.go:171","msg":"trace[199359563] transaction","detail":"{read_only:false; response_revision:6064; number_of_response:1; }","duration":"123.971738ms","start":"2026-04-20T10:26:32.616862Z","end":"2026-04-20T10:26:32.740834Z","steps":["trace[199359563] 'process raft request' (duration: 123.742113ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:26:43.373476Z","caller":"traceutil/trace.go:171","msg":"trace[384575136] transaction","detail":"{read_only:false; response_revision:6122; number_of_response:1; }","duration":"111.405604ms","start":"2026-04-20T10:26:43.262024Z","end":"2026-04-20T10:26:43.373429Z","steps":["trace[384575136] 'process raft request' (duration: 111.102457ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:26:43.448265Z","caller":"traceutil/trace.go:171","msg":"trace[291009582] linearizableReadLoop","detail":"{readStateIndex:6345; appliedIndex:6343; }","duration":"168.057483ms","start":"2026-04-20T10:26:43.280171Z","end":"2026-04-20T10:26:43.448229Z","steps":["trace[291009582] 'read index received' (duration: 93.030393ms)","trace[291009582] 'applied index is now lower than readState.Index' (duration: 75.02578ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:26:43.448374Z","caller":"traceutil/trace.go:171","msg":"trace[2077358342] transaction","detail":"{read_only:false; response_revision:6123; number_of_response:1; }","duration":"184.518419ms","start":"2026-04-20T10:26:43.263821Z","end":"2026-04-20T10:26:43.448339Z","steps":["trace[2077358342] 'process raft request' (duration: 133.8118ms)","trace[2077358342] 'compare' (duration: 50.419713ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:26:43.448449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.242957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:26:43.448511Z","caller":"traceutil/trace.go:171","msg":"trace[117301007] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:6123; }","duration":"168.36474ms","start":"2026-04-20T10:26:43.280128Z","end":"2026-04-20T10:26:43.448493Z","steps":["trace[117301007] 'agreement among raft nodes before linearized reading' (duration: 168.238007ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:26:44.197229Z","caller":"traceutil/trace.go:171","msg":"trace[47305940] linearizableReadLoop","detail":"{readStateIndex:6348; appliedIndex:6345; }","duration":"132.103359ms","start":"2026-04-20T10:26:44.065097Z","end":"2026-04-20T10:26:44.1972Z","steps":["trace[47305940] 'read index received' (duration: 12.985295ms)","trace[47305940] 'applied index is now lower than readState.Index' (duration: 119.116604ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:26:44.197306Z","caller":"traceutil/trace.go:171","msg":"trace[1732588130] transaction","detail":"{read_only:false; response_revision:6125; number_of_response:1; }","duration":"141.322576ms","start":"2026-04-20T10:26:44.055942Z","end":"2026-04-20T10:26:44.197264Z","steps":["trace[1732588130] 'process raft request' (duration: 110.42179ms)","trace[1732588130] 'compare' (duration: 30.601219ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:26:44.197402Z","caller":"traceutil/trace.go:171","msg":"trace[653903619] transaction","detail":"{read_only:false; response_revision:6126; number_of_response:1; }","duration":"139.311768ms","start":"2026-04-20T10:26:44.058076Z","end":"2026-04-20T10:26:44.197388Z","steps":["trace[653903619] 'process raft request' (duration: 139.049902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:26:44.197542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.948856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:26:44.197574Z","caller":"traceutil/trace.go:171","msg":"trace[583333522] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:6126; }","duration":"132.010237ms","start":"2026-04-20T10:26:44.065558Z","end":"2026-04-20T10:26:44.197568Z","steps":["trace[583333522] 'agreement among raft nodes before linearized reading' (duration: 131.931155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:26:44.197728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.606251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:26:44.197841Z","caller":"traceutil/trace.go:171","msg":"trace[1015612784] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:6126; }","duration":"132.746134ms","start":"2026-04-20T10:26:44.065049Z","end":"2026-04-20T10:26:44.197795Z","steps":["trace[1015612784] 'agreement among raft nodes before linearized reading' (duration: 132.548049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:26:44.197895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.171764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"info","ts":"2026-04-20T10:26:44.198042Z","caller":"traceutil/trace.go:171","msg":"trace[2035971011] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:6126; }","duration":"124.370327ms","start":"2026-04-20T10:26:44.073651Z","end":"2026-04-20T10:26:44.198021Z","steps":["trace[2035971011] 'agreement among raft nodes before linearized reading' (duration: 123.960728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:16.83461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.991725ms","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-20T10:27:16.8347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.904615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T10:27:16.834745Z","caller":"traceutil/trace.go:171","msg":"trace[565701227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6381; }","duration":"120.175419ms","start":"2026-04-20T10:27:16.714541Z","end":"2026-04-20T10:27:16.834716Z","steps":["trace[565701227] 'range keys from in-memory index tree' (duration: 119.670957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:16.834769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.975078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T10:27:16.834802Z","caller":"traceutil/trace.go:171","msg":"trace[108403090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6381; }","duration":"135.089689ms","start":"2026-04-20T10:27:16.699684Z","end":"2026-04-20T10:27:16.834774Z","steps":["trace[108403090] 'range keys from in-memory index tree' (duration: 134.620318ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:16.834841Z","caller":"traceutil/trace.go:171","msg":"trace[480304865] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6381; }","duration":"127.04645ms","start":"2026-04-20T10:27:16.707774Z","end":"2026-04-20T10:27:16.83482Z","steps":["trace[480304865] 'range keys from in-memory index tree' (duration: 126.797044ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:29:39.023883Z","caller":"traceutil/trace.go:171","msg":"trace[1531382313] transaction","detail":"{read_only:false; response_revision:7244; number_of_response:1; }","duration":"120.046011ms","start":"2026-04-20T10:29:38.903799Z","end":"2026-04-20T10:29:39.023845Z","steps":["trace[1531382313] 'process raft request' (duration: 119.626131ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:29:39.276773Z","caller":"traceutil/trace.go:171","msg":"trace[19184171] transaction","detail":"{read_only:false; response_revision:7254; number_of_response:1; }","duration":"112.786063ms","start":"2026-04-20T10:29:39.163938Z","end":"2026-04-20T10:29:39.276724Z","steps":["trace[19184171] 'process raft request' (duration: 85.47622ms)","trace[19184171] 'compare' (duration: 26.830532ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:29:39.304646Z","caller":"traceutil/trace.go:171","msg":"trace[2072062595] linearizableReadLoop","detail":"{readStateIndex:7517; appliedIndex:7515; }","duration":"119.443467ms","start":"2026-04-20T10:29:39.185171Z","end":"2026-04-20T10:29:39.304614Z","steps":["trace[2072062595] 'read index received' (duration: 64.268419ms)","trace[2072062595] 'applied index is now lower than readState.Index' (duration: 55.173228ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:29:39.304868Z","caller":"traceutil/trace.go:171","msg":"trace[480448477] transaction","detail":"{read_only:false; response_revision:7255; number_of_response:1; }","duration":"124.516914ms","start":"2026-04-20T10:29:39.180285Z","end":"2026-04-20T10:29:39.304802Z","steps":["trace[480448477] 'process raft request' (duration: 124.181706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:29:39.305058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.866376ms","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-20T10:29:39.305112Z","caller":"traceutil/trace.go:171","msg":"trace[1694019994] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:7255; }","duration":"119.992119ms","start":"2026-04-20T10:29:39.185107Z","end":"2026-04-20T10:29:39.305099Z","steps":["trace[1694019994] 'agreement among raft nodes before linearized reading' (duration: 119.653551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:29:39.418776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.836588ms","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-20T10:29:39.418885Z","caller":"traceutil/trace.go:171","msg":"trace[1226702358] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:7259; }","duration":"104.992472ms","start":"2026-04-20T10:29:39.313863Z","end":"2026-04-20T10:29:39.418856Z","steps":["trace[1226702358] 'agreement among raft nodes before linearized reading' (duration: 104.659124ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:29:39.55703Z","caller":"traceutil/trace.go:171","msg":"trace[1820835226] transaction","detail":"{read_only:false; response_revision:7263; number_of_response:1; }","duration":"125.60912ms","start":"2026-04-20T10:29:39.431339Z","end":"2026-04-20T10:29:39.556948Z","steps":["trace[1820835226] 'process raft request' (duration: 113.023448ms)","trace[1820835226] 'compare' (duration: 12.245524ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:29:39.970363Z","caller":"traceutil/trace.go:171","msg":"trace[1524456433] transaction","detail":"{read_only:false; response_revision:7269; number_of_response:1; }","duration":"127.7762ms","start":"2026-04-20T10:29:39.842537Z","end":"2026-04-20T10:29:39.970313Z","steps":["trace[1524456433] 'process raft request' (duration: 96.236459ms)","trace[1524456433] 'compare' (duration: 31.274435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:29:39.970711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.54467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-barbican-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:29:39.97082Z","caller":"traceutil/trace.go:171","msg":"trace[479410514] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-barbican-server; range_end:; response_count:0; response_revision:7270; }","duration":"129.667523ms","start":"2026-04-20T10:29:39.841129Z","end":"2026-04-20T10:29:39.970797Z","steps":["trace[479410514] 'agreement among raft nodes before linearized reading' (duration: 129.433158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:29:39.970927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.709758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-20T10:29:39.970999Z","caller":"traceutil/trace.go:171","msg":"trace[1611767533] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:7270; }","duration":"127.847361ms","start":"2026-04-20T10:29:39.843133Z","end":"2026-04-20T10:29:39.97098Z","steps":["trace[1611767533] 'agreement among raft nodes before linearized reading' (duration: 127.646487ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:29:40.172399Z","caller":"traceutil/trace.go:171","msg":"trace[18847751] linearizableReadLoop","detail":"{readStateIndex:7536; appliedIndex:7535; }","duration":"134.467375ms","start":"2026-04-20T10:29:40.037891Z","end":"2026-04-20T10:29:40.172358Z","steps":["trace[18847751] 'read index received' (duration: 119.877107ms)","trace[18847751] 'applied index is now lower than readState.Index' (duration: 14.589118ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:29:40.172449Z","caller":"traceutil/trace.go:171","msg":"trace[1892116212] transaction","detail":"{read_only:false; response_revision:7273; number_of_response:1; }","duration":"178.598157ms","start":"2026-04-20T10:29:39.993796Z","end":"2026-04-20T10:29:40.172395Z","steps":["trace[1892116212] 'process raft request' (duration: 163.882836ms)","trace[1892116212] 'compare' (duration: 14.510826ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:29:40.172744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.810703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-20T10:29:40.17285Z","caller":"traceutil/trace.go:171","msg":"trace[794799801] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:7273; }","duration":"134.964346ms","start":"2026-04-20T10:29:40.037861Z","end":"2026-04-20T10:29:40.172826Z","steps":["trace[794799801] 'agreement among raft nodes before linearized reading' (duration: 134.631948ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:29:40.204926Z","caller":"traceutil/trace.go:171","msg":"trace[1643092603] transaction","detail":"{read_only:false; response_revision:7274; number_of_response:1; }","duration":"161.561113ms","start":"2026-04-20T10:29:40.043328Z","end":"2026-04-20T10:29:40.204889Z","steps":["trace[1643092603] 'process raft request' (duration: 161.200814ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:29:40.205034Z","caller":"traceutil/trace.go:171","msg":"trace[890786837] transaction","detail":"{read_only:false; response_revision:7275; number_of_response:1; }","duration":"161.521852ms","start":"2026-04-20T10:29:40.043462Z","end":"2026-04-20T10:29:40.204984Z","steps":["trace[890786837] 'process raft request' (duration: 161.265656ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:29:40.205231Z","caller":"traceutil/trace.go:171","msg":"trace[2020418909] transaction","detail":"{read_only:false; response_revision:7276; number_of_response:1; }","duration":"156.131347ms","start":"2026-04-20T10:29:40.049073Z","end":"2026-04-20T10:29:40.205204Z","steps":["trace[2020418909] 'process raft request' (duration: 155.760708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:29:53.262804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.104118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-20T10:29:53.262894Z","caller":"traceutil/trace.go:171","msg":"trace[1765341171] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7395; }","duration":"299.250682ms","start":"2026-04-20T10:29:52.963622Z","end":"2026-04-20T10:29:53.262873Z","steps":["trace[1765341171] 'range keys from in-memory index tree' (duration: 298.844492ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:29:53.269613Z","caller":"traceutil/trace.go:171","msg":"trace[1273974560] transaction","detail":"{read_only:false; response_revision:7396; number_of_response:1; }","duration":"296.57093ms","start":"2026-04-20T10:29:52.973014Z","end":"2026-04-20T10:29:53.269585Z","steps":["trace[1273974560] 'process raft request' (duration: 296.389986ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:29:53.278729Z","caller":"traceutil/trace.go:171","msg":"trace[368725343] transaction","detail":"{read_only:false; response_revision:7397; number_of_response:1; }","duration":"272.890121ms","start":"2026-04-20T10:29:53.00581Z","end":"2026-04-20T10:29:53.278701Z","steps":["trace[368725343] 'process raft request' (duration: 272.682956ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:29:53.279143Z","caller":"traceutil/trace.go:171","msg":"trace[1184270947] transaction","detail":"{read_only:false; response_revision:7398; number_of_response:1; }","duration":"147.620309ms","start":"2026-04-20T10:29:53.131512Z","end":"2026-04-20T10:29:53.279133Z","steps":["trace[1184270947] 'process raft request' (duration: 147.107077ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:29:53.279217Z","caller":"traceutil/trace.go:171","msg":"trace[650819498] transaction","detail":"{read_only:false; response_revision:7399; number_of_response:1; }","duration":"147.481056ms","start":"2026-04-20T10:29:53.131693Z","end":"2026-04-20T10:29:53.279174Z","steps":["trace[650819498] 'process raft request' (duration: 147.277471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:29:53.279341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.404419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:29:53.279377Z","caller":"traceutil/trace.go:171","msg":"trace[2131183195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7399; }","duration":"262.488921ms","start":"2026-04-20T10:29:53.016878Z","end":"2026-04-20T10:29:53.279367Z","steps":["trace[2131183195] 'agreement among raft nodes before linearized reading' (duration: 262.403409ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:29:53.279184Z","caller":"traceutil/trace.go:171","msg":"trace[1254338687] linearizableReadLoop","detail":"{readStateIndex:7663; appliedIndex:7661; }","duration":"262.225545ms","start":"2026-04-20T10:29:53.01692Z","end":"2026-04-20T10:29:53.279145Z","steps":["trace[1254338687] 'read index received' (duration: 252.526ms)","trace[1254338687] 'applied index is now lower than readState.Index' (duration: 9.698184ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:30:15.910242Z","caller":"traceutil/trace.go:171","msg":"trace[1552729526] transaction","detail":"{read_only:false; response_revision:7603; number_of_response:1; }","duration":"101.514131ms","start":"2026-04-20T10:30:15.808691Z","end":"2026-04-20T10:30:15.910205Z","steps":["trace[1552729526] 'process raft request' (duration: 101.174613ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:31:16.621693Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5880} {"level":"info","ts":"2026-04-20T10:31:16.71564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5880,"took":"91.57829ms","hash":721105488,"current-db-size-bytes":56061952,"current-db-size":"56 MB","current-db-size-in-use-bytes":26054656,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-20T10:31:16.715703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":721105488,"revision":5880,"compact-revision":2962} {"level":"warn","ts":"2026-04-20T10:31:56.760203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.042038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-20T10:31:56.760335Z","caller":"traceutil/trace.go:171","msg":"trace[703316721] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8310; }","duration":"105.232352ms","start":"2026-04-20T10:31:56.655072Z","end":"2026-04-20T10:31:56.760304Z","steps":["trace[703316721] 'range keys from in-memory index tree' (duration: 104.879195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:04.788141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.480873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:32:04.788532Z","caller":"traceutil/trace.go:171","msg":"trace[33460342] transaction","detail":"{read_only:false; response_revision:8359; number_of_response:1; }","duration":"143.820097ms","start":"2026-04-20T10:32:04.644696Z","end":"2026-04-20T10:32:04.788516Z","steps":["trace[33460342] 'process raft request' (duration: 15.883124ms)","trace[33460342] 'compare' (duration: 127.36771ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:32:05.239215Z","caller":"traceutil/trace.go:171","msg":"trace[4040232] linearizableReadLoop","detail":"{readStateIndex:8662; appliedIndex:8661; }","duration":"134.361ms","start":"2026-04-20T10:32:05.104823Z","end":"2026-04-20T10:32:05.239184Z","steps":["trace[4040232] 'read index received' (duration: 134.127805ms)","trace[4040232] 'applied index is now lower than readState.Index' (duration: 231.205µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:32:05.23928Z","caller":"traceutil/trace.go:171","msg":"trace[762389957] transaction","detail":"{read_only:false; response_revision:8362; number_of_response:1; }","duration":"215.321856ms","start":"2026-04-20T10:32:05.023909Z","end":"2026-04-20T10:32:05.239231Z","steps":["trace[762389957] 'process raft request' (duration: 215.04168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:05.239427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.564724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:32:05.239708Z","caller":"traceutil/trace.go:171","msg":"trace[1369560284] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:8362; }","duration":"134.917953ms","start":"2026-04-20T10:32:05.104763Z","end":"2026-04-20T10:32:05.239681Z","steps":["trace[1369560284] 'agreement among raft nodes before linearized reading' (duration: 134.528164ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:32:11.23231Z","caller":"traceutil/trace.go:171","msg":"trace[743063190] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"154.450741ms","start":"2026-04-20T10:32:11.07781Z","end":"2026-04-20T10:32:11.232261Z","steps":["trace[743063190] 'process raft request' (duration: 154.150164ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:32:13.173685Z","caller":"traceutil/trace.go:171","msg":"trace[883614981] linearizableReadLoop","detail":"{readStateIndex:8832; appliedIndex:8831; }","duration":"484.944427ms","start":"2026-04-20T10:32:12.688718Z","end":"2026-04-20T10:32:13.173663Z","steps":["trace[883614981] 'read index received' (duration: 484.855274ms)","trace[883614981] 'applied index is now lower than readState.Index' (duration: 87.222µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:32:13.173799Z","caller":"traceutil/trace.go:171","msg":"trace[1476318578] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"538.122416ms","start":"2026-04-20T10:32:12.635665Z","end":"2026-04-20T10:32:13.173787Z","steps":["trace[1476318578] 'process raft request' (duration: 537.84654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:13.173913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:32:12.635646Z","time spent":"538.181347ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:32:13.17406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.341095ms","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-20T10:32:13.174116Z","caller":"traceutil/trace.go:171","msg":"trace[1513623038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8529; }","duration":"485.421788ms","start":"2026-04-20T10:32:12.688681Z","end":"2026-04-20T10:32:13.174103Z","steps":["trace[1513623038] 'agreement among raft nodes before linearized reading' (duration: 485.289255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:13.174149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:32:12.688664Z","time spent":"485.478699ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T10:32:13.674572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409855410079961,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T10:32:13.764735Z","caller":"traceutil/trace.go:171","msg":"trace[1791215896] linearizableReadLoop","detail":"{readStateIndex:8834; appliedIndex:8832; }","duration":"590.957106ms","start":"2026-04-20T10:32:13.173753Z","end":"2026-04-20T10:32:13.76471Z","steps":["trace[1791215896] 'read index received' (duration: 565.690377ms)","trace[1791215896] 'applied index is now lower than readState.Index' (duration: 25.265779ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:32:13.764871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027851592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:32:13.764805Z","caller":"traceutil/trace.go:171","msg":"trace[535526993] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"1.032008767s","start":"2026-04-20T10:32:12.732723Z","end":"2026-04-20T10:32:13.764732Z","steps":["trace[535526993] 'process raft request' (duration: 1.006705147s)","trace[535526993] 'compare' (duration: 25.101705ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:32:13.764976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.020449ms","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-20T10:32:13.765018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.273133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-77748b585c-8dwmj.18a80a117a3d7a5c\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-20T10:32:13.765038Z","caller":"traceutil/trace.go:171","msg":"trace[828457216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8531; }","duration":"930.124272ms","start":"2026-04-20T10:32:12.834899Z","end":"2026-04-20T10:32:13.765023Z","steps":["trace[828457216] 'agreement among raft nodes before linearized reading' (duration: 929.937687ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:32:13.765051Z","caller":"traceutil/trace.go:171","msg":"trace[1706134950] range","detail":"{range_begin:/registry/events/openstack/barbican-api-77748b585c-8dwmj.18a80a117a3d7a5c; range_end:; response_count:1; response_revision:8531; }","duration":"584.326045ms","start":"2026-04-20T10:32:13.180715Z","end":"2026-04-20T10:32:13.765041Z","steps":["trace[1706134950] 'agreement among raft nodes before linearized reading' (duration: 584.219173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:13.765077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:32:12.834876Z","time spent":"930.191834ms","remote":"127.0.0.1:39948","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-20T10:32:13.765055Z","caller":"traceutil/trace.go:171","msg":"trace[469046516] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"586.522775ms","start":"2026-04-20T10:32:13.178492Z","end":"2026-04-20T10:32:13.765015Z","steps":["trace[469046516] 'process raft request' (duration: 586.168847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:13.765295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:32:12.732693Z","time spent":"1.032278203s","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:32:13.765315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.902723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:32:13.765388Z","caller":"traceutil/trace.go:171","msg":"trace[910984460] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:8531; }","duration":"289.040466ms","start":"2026-04-20T10:32:13.476328Z","end":"2026-04-20T10:32:13.765369Z","steps":["trace[910984460] 'agreement among raft nodes before linearized reading' (duration: 288.637956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:13.765406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:32:13.178451Z","time spent":"586.855363ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:32:13.765446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:32:13.180701Z","time spent":"584.379766ms","remote":"127.0.0.1:39758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1157,"request content":"key:\"/registry/events/openstack/barbican-api-77748b585c-8dwmj.18a80a117a3d7a5c\" "} {"level":"warn","ts":"2026-04-20T10:32:13.765481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.037774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T10:32:13.764904Z","caller":"traceutil/trace.go:171","msg":"trace[241810367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8531; }","duration":"1.027944155s","start":"2026-04-20T10:32:12.736949Z","end":"2026-04-20T10:32:13.764893Z","steps":["trace[241810367] 'agreement among raft nodes before linearized reading' (duration: 1.027875723s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:13.765568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.868254ms","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-20T10:32:13.765622Z","caller":"traceutil/trace.go:171","msg":"trace[1791069524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8531; }","duration":"528.961476ms","start":"2026-04-20T10:32:13.236645Z","end":"2026-04-20T10:32:13.765606Z","steps":["trace[1791069524] 'agreement among raft nodes before linearized reading' (duration: 528.820373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:13.765623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:32:12.736928Z","time spent":"1.028681071s","remote":"127.0.0.1:39716","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-20T10:32:13.765637Z","caller":"traceutil/trace.go:171","msg":"trace[1564139664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8531; }","duration":"904.265869ms","start":"2026-04-20T10:32:12.86133Z","end":"2026-04-20T10:32:13.765596Z","steps":["trace[1564139664] 'agreement among raft nodes before linearized reading' (duration: 903.612964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:13.765669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:32:13.236623Z","time spent":"529.039427ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T10:32:13.765667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.266287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4qggz\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-20T10:32:13.765788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.676767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:32:13.765807Z","caller":"traceutil/trace.go:171","msg":"trace[1338305502] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4qggz; range_end:; response_count:1; response_revision:8531; }","duration":"893.470252ms","start":"2026-04-20T10:32:12.872307Z","end":"2026-04-20T10:32:13.765778Z","steps":["trace[1338305502] 'agreement among raft nodes before linearized reading' (duration: 892.460429ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:32:13.765824Z","caller":"traceutil/trace.go:171","msg":"trace[1559388075] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:8531; }","duration":"606.715178ms","start":"2026-04-20T10:32:13.159098Z","end":"2026-04-20T10:32:13.765813Z","steps":["trace[1559388075] 'agreement among raft nodes before linearized reading' (duration: 606.642336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:13.765873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:32:13.159039Z","time spent":"606.826031ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":9,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:32:13.765711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:32:12.861302Z","time spent":"904.395062ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T10:32:13.765921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:32:12.872294Z","time spent":"893.581234ms","remote":"127.0.0.1:39852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-4qggz\" "} {"level":"info","ts":"2026-04-20T10:32:24.22333Z","caller":"traceutil/trace.go:171","msg":"trace[883638643] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"141.742749ms","start":"2026-04-20T10:32:24.081549Z","end":"2026-04-20T10:32:24.223292Z","steps":["trace[883638643] 'process raft request' (duration: 141.473153ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:32:24.237173Z","caller":"traceutil/trace.go:171","msg":"trace[1636555370] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"102.493889ms","start":"2026-04-20T10:32:24.134643Z","end":"2026-04-20T10:32:24.237137Z","steps":["trace[1636555370] 'process raft request' (duration: 102.386207ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:32:24.237403Z","caller":"traceutil/trace.go:171","msg":"trace[949639747] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"105.642572ms","start":"2026-04-20T10:32:24.131734Z","end":"2026-04-20T10:32:24.237377Z","steps":["trace[949639747] 'process raft request' (duration: 105.032118ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:32:24.237567Z","caller":"traceutil/trace.go:171","msg":"trace[1227920497] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"104.817723ms","start":"2026-04-20T10:32:24.132739Z","end":"2026-04-20T10:32:24.237556Z","steps":["trace[1227920497] 'process raft request' (duration: 104.23754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:25.262618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.558941ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T10:32:25.940638Z","caller":"traceutil/trace.go:171","msg":"trace[755893169] linearizableReadLoop","detail":"{readStateIndex:8900; appliedIndex:8899; }","duration":"271.259358ms","start":"2026-04-20T10:32:25.669351Z","end":"2026-04-20T10:32:25.94061Z","steps":["trace[755893169] 'read index received' (duration: 271.077744ms)","trace[755893169] 'applied index is now lower than readState.Index' (duration: 179.964µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:32:25.940786Z","caller":"traceutil/trace.go:171","msg":"trace[1548858528] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"670.945519ms","start":"2026-04-20T10:32:25.269793Z","end":"2026-04-20T10:32:25.940738Z","steps":["trace[1548858528] 'process raft request' (duration: 670.617741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:25.941018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:32:25.269761Z","time spent":"671.144514ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:32:25.940834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.26499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:32:25.941146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.733715ms","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-20T10:32:25.941163Z","caller":"traceutil/trace.go:171","msg":"trace[1912261176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8594; }","duration":"200.617918ms","start":"2026-04-20T10:32:25.740537Z","end":"2026-04-20T10:32:25.941154Z","steps":["trace[1912261176] 'agreement among raft nodes before linearized reading' (duration: 200.2397ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:32:25.941188Z","caller":"traceutil/trace.go:171","msg":"trace[468530340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8594; }","duration":"119.829227ms","start":"2026-04-20T10:32:25.82135Z","end":"2026-04-20T10:32:25.941179Z","steps":["trace[468530340] 'agreement among raft nodes before linearized reading' (duration: 119.609042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:32:25.941165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.636717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:32:25.94129Z","caller":"traceutil/trace.go:171","msg":"trace[1700064916] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8594; }","duration":"271.972115ms","start":"2026-04-20T10:32:25.669288Z","end":"2026-04-20T10:32:25.941261Z","steps":["trace[1700064916] 'agreement among raft nodes before linearized reading' (duration: 271.458003ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:32:28.140184Z","caller":"traceutil/trace.go:171","msg":"trace[187339305] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"105.268834ms","start":"2026-04-20T10:32:28.034895Z","end":"2026-04-20T10:32:28.140164Z","steps":["trace[187339305] 'process raft request' (duration: 105.168151ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:32:28.140201Z","caller":"traceutil/trace.go:171","msg":"trace[308166984] transaction","detail":"{read_only:false; response_revision:8605; number_of_response:1; }","duration":"105.316074ms","start":"2026-04-20T10:32:28.034839Z","end":"2026-04-20T10:32:28.140155Z","steps":["trace[308166984] 'process raft request' (duration: 84.129619ms)","trace[308166984] 'compare' (duration: 20.982851ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:32:28.140395Z","caller":"traceutil/trace.go:171","msg":"trace[1482470212] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"104.704511ms","start":"2026-04-20T10:32:28.035681Z","end":"2026-04-20T10:32:28.140386Z","steps":["trace[1482470212] 'process raft request' (duration: 104.419644ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:32:28.140388Z","caller":"traceutil/trace.go:171","msg":"trace[175542824] linearizableReadLoop","detail":"{readStateIndex:8913; appliedIndex:8909; }","duration":"103.887141ms","start":"2026-04-20T10:32:28.03647Z","end":"2026-04-20T10:32:28.140358Z","steps":["trace[175542824] 'read index received' (duration: 13.264334ms)","trace[175542824] 'applied index is now lower than readState.Index' (duration: 90.619967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:32:28.140994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.506026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224139"} {"level":"info","ts":"2026-04-20T10:32:28.14103Z","caller":"traceutil/trace.go:171","msg":"trace[1373673951] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8607; }","duration":"104.568077ms","start":"2026-04-20T10:32:28.036452Z","end":"2026-04-20T10:32:28.14102Z","steps":["trace[1373673951] 'agreement among raft nodes before linearized reading' (duration: 104.027095ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:32:59.863858Z","caller":"traceutil/trace.go:171","msg":"trace[415528577] transaction","detail":"{read_only:false; response_revision:8829; number_of_response:1; }","duration":"105.759765ms","start":"2026-04-20T10:32:59.758066Z","end":"2026-04-20T10:32:59.863825Z","steps":["trace[415528577] 'process raft request' (duration: 105.50828ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:00.40097Z","caller":"traceutil/trace.go:171","msg":"trace[644700757] linearizableReadLoop","detail":"{readStateIndex:9147; appliedIndex:9146; }","duration":"179.817672ms","start":"2026-04-20T10:33:00.221125Z","end":"2026-04-20T10:33:00.400943Z","steps":["trace[644700757] 'read index received' (duration: 179.549626ms)","trace[644700757] 'applied index is now lower than readState.Index' (duration: 267.046µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:33:00.401145Z","caller":"traceutil/trace.go:171","msg":"trace[474640269] transaction","detail":"{read_only:false; response_revision:8831; number_of_response:1; }","duration":"201.914946ms","start":"2026-04-20T10:33:00.199166Z","end":"2026-04-20T10:33:00.401081Z","steps":["trace[474640269] 'process raft request' (duration: 201.599419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:33:00.401251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.098588ms","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-20T10:33:00.401291Z","caller":"traceutil/trace.go:171","msg":"trace[618071986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8831; }","duration":"180.191699ms","start":"2026-04-20T10:33:00.221088Z","end":"2026-04-20T10:33:00.401279Z","steps":["trace[618071986] 'agreement among raft nodes before linearized reading' (duration: 180.005705ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:00.608028Z","caller":"traceutil/trace.go:171","msg":"trace[1732438920] transaction","detail":"{read_only:false; response_revision:8835; number_of_response:1; }","duration":"200.487783ms","start":"2026-04-20T10:33:00.407501Z","end":"2026-04-20T10:33:00.607989Z","steps":["trace[1732438920] 'process raft request' (duration: 200.371991ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:00.608041Z","caller":"traceutil/trace.go:171","msg":"trace[928843484] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"201.035096ms","start":"2026-04-20T10:33:00.406943Z","end":"2026-04-20T10:33:00.607978Z","steps":["trace[928843484] 'process raft request' (duration: 169.433769ms)","trace[928843484] 'compare' (duration: 31.176007ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:33:00.608332Z","caller":"traceutil/trace.go:171","msg":"trace[935157847] transaction","detail":"{read_only:false; response_revision:8837; number_of_response:1; }","duration":"193.401086ms","start":"2026-04-20T10:33:00.414917Z","end":"2026-04-20T10:33:00.608318Z","steps":["trace[935157847] 'process raft request' (duration: 193.229472ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:00.608334Z","caller":"traceutil/trace.go:171","msg":"trace[289584896] transaction","detail":"{read_only:false; response_revision:8836; number_of_response:1; }","duration":"193.934847ms","start":"2026-04-20T10:33:00.414343Z","end":"2026-04-20T10:33:00.608278Z","steps":["trace[289584896] 'process raft request' (duration: 193.575279ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:00.608435Z","caller":"traceutil/trace.go:171","msg":"trace[2023652669] transaction","detail":"{read_only:false; response_revision:8838; number_of_response:1; }","duration":"139.246034ms","start":"2026-04-20T10:33:00.469177Z","end":"2026-04-20T10:33:00.608423Z","steps":["trace[2023652669] 'process raft request' (duration: 139.09207ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:00.608486Z","caller":"traceutil/trace.go:171","msg":"trace[751687871] transaction","detail":"{read_only:false; response_revision:8839; number_of_response:1; }","duration":"139.183143ms","start":"2026-04-20T10:33:00.469291Z","end":"2026-04-20T10:33:00.608474Z","steps":["trace[751687871] 'process raft request' (duration: 139.020739ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:00.608179Z","caller":"traceutil/trace.go:171","msg":"trace[807313440] transaction","detail":"{read_only:false; response_revision:8834; number_of_response:1; }","duration":"200.758639ms","start":"2026-04-20T10:33:00.407377Z","end":"2026-04-20T10:33:00.608136Z","steps":["trace[807313440] 'process raft request' (duration: 200.435402ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:00.608425Z","caller":"traceutil/trace.go:171","msg":"trace[1325763904] transaction","detail":"{read_only:false; response_revision:8840; number_of_response:1; }","duration":"121.532837ms","start":"2026-04-20T10:33:00.486871Z","end":"2026-04-20T10:33:00.608404Z","steps":["trace[1325763904] 'process raft request' (duration: 121.473386ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:00.60837Z","caller":"traceutil/trace.go:171","msg":"trace[1869658862] linearizableReadLoop","detail":"{readStateIndex:9151; appliedIndex:9147; }","duration":"200.258018ms","start":"2026-04-20T10:33:00.408084Z","end":"2026-04-20T10:33:00.608342Z","steps":["trace[1869658862] 'read index received' (duration: 44.967765ms)","trace[1869658862] 'applied index is now lower than readState.Index' (duration: 155.289433ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:33:00.608695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.607196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228870"} {"level":"info","ts":"2026-04-20T10:33:00.608738Z","caller":"traceutil/trace.go:171","msg":"trace[939837606] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8840; }","duration":"200.705599ms","start":"2026-04-20T10:33:00.408026Z","end":"2026-04-20T10:33:00.608732Z","steps":["trace[939837606] 'agreement among raft nodes before linearized reading' (duration: 200.389112ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:10.20729Z","caller":"traceutil/trace.go:171","msg":"trace[1544003999] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8927; }","duration":"104.64969ms","start":"2026-04-20T10:33:10.102613Z","end":"2026-04-20T10:33:10.207262Z","steps":["trace[1544003999] 'process raft request' (duration: 89.576683ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:10.216309Z","caller":"traceutil/trace.go:171","msg":"trace[106504997] transaction","detail":"{read_only:false; response_revision:8928; number_of_response:1; }","duration":"112.463824ms","start":"2026-04-20T10:33:10.103817Z","end":"2026-04-20T10:33:10.21628Z","steps":["trace[106504997] 'process raft request' (duration: 111.975614ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:10.836123Z","caller":"traceutil/trace.go:171","msg":"trace[973065504] linearizableReadLoop","detail":"{readStateIndex:9257; appliedIndex:9253; }","duration":"171.094926ms","start":"2026-04-20T10:33:10.665004Z","end":"2026-04-20T10:33:10.836099Z","steps":["trace[973065504] 'read index received' (duration: 75.57965ms)","trace[973065504] 'applied index is now lower than readState.Index' (duration: 95.514396ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:33:10.836171Z","caller":"traceutil/trace.go:171","msg":"trace[957519250] transaction","detail":"{read_only:false; response_revision:8933; number_of_response:1; }","duration":"175.530745ms","start":"2026-04-20T10:33:10.660592Z","end":"2026-04-20T10:33:10.836123Z","steps":["trace[957519250] 'process raft request' (duration: 175.29463ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:10.836254Z","caller":"traceutil/trace.go:171","msg":"trace[958724460] transaction","detail":"{read_only:false; response_revision:8935; number_of_response:1; }","duration":"174.112434ms","start":"2026-04-20T10:33:10.662117Z","end":"2026-04-20T10:33:10.83623Z","steps":["trace[958724460] 'process raft request' (duration: 173.94825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:33:10.836386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.352403ms","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-20T10:33:10.836427Z","caller":"traceutil/trace.go:171","msg":"trace[309390977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8935; }","duration":"171.466545ms","start":"2026-04-20T10:33:10.664948Z","end":"2026-04-20T10:33:10.836414Z","steps":["trace[309390977] 'agreement among raft nodes before linearized reading' (duration: 171.265791ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:10.836613Z","caller":"traceutil/trace.go:171","msg":"trace[41750320] transaction","detail":"{read_only:false; response_revision:8934; number_of_response:1; }","duration":"174.487783ms","start":"2026-04-20T10:33:10.662042Z","end":"2026-04-20T10:33:10.836529Z","steps":["trace[41750320] 'process raft request' (duration: 173.993132ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:11.185198Z","caller":"traceutil/trace.go:171","msg":"trace[450062568] linearizableReadLoop","detail":"{readStateIndex:9259; appliedIndex:9257; }","duration":"348.991854ms","start":"2026-04-20T10:33:10.836185Z","end":"2026-04-20T10:33:11.185177Z","steps":["trace[450062568] 'read index received' (duration: 267.721757ms)","trace[450062568] 'applied index is now lower than readState.Index' (duration: 81.269167ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:33:11.185215Z","caller":"traceutil/trace.go:171","msg":"trace[431193183] transaction","detail":"{read_only:false; response_revision:8936; number_of_response:1; }","duration":"423.099362ms","start":"2026-04-20T10:33:10.762078Z","end":"2026-04-20T10:33:11.185177Z","steps":["trace[431193183] 'process raft request' (duration: 341.817715ms)","trace[431193183] 'compare' (duration: 80.798177ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:33:11.185336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:33:10.762061Z","time spent":"423.209975ms","remote":"127.0.0.1:39758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:33:11.185471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.391463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-20T10:33:11.185432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.698977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:33:11.1855Z","caller":"traceutil/trace.go:171","msg":"trace[385567217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8937; }","duration":"518.484776ms","start":"2026-04-20T10:33:10.667006Z","end":"2026-04-20T10:33:11.185491Z","steps":["trace[385567217] 'agreement among raft nodes before linearized reading' (duration: 518.276051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:33:11.185472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.390274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4qggz\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-20T10:33:11.185523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:33:10.666991Z","time spent":"518.526546ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T10:33:11.185532Z","caller":"traceutil/trace.go:171","msg":"trace[1531792584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8937; }","duration":"445.851471ms","start":"2026-04-20T10:33:10.739656Z","end":"2026-04-20T10:33:11.185508Z","steps":["trace[1531792584] 'agreement among raft nodes before linearized reading' (duration: 445.654206ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:11.185558Z","caller":"traceutil/trace.go:171","msg":"trace[275857151] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4qggz; range_end:; response_count:1; response_revision:8937; }","duration":"510.500426ms","start":"2026-04-20T10:33:10.675032Z","end":"2026-04-20T10:33:11.185533Z","steps":["trace[275857151] 'agreement among raft nodes before linearized reading' (duration: 510.22053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:33:11.185518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.587165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-20T10:33:11.185582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:33:10.739645Z","time spent":"445.927252ms","remote":"127.0.0.1:39716","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-20T10:33:11.185609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:33:10.675016Z","time spent":"510.584118ms","remote":"127.0.0.1:39852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-4qggz\" "} {"level":"info","ts":"2026-04-20T10:33:11.185634Z","caller":"traceutil/trace.go:171","msg":"trace[1120315654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8937; }","duration":"517.728108ms","start":"2026-04-20T10:33:10.667878Z","end":"2026-04-20T10:33:11.185607Z","steps":["trace[1120315654] 'agreement among raft nodes before linearized reading' (duration: 517.37812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:33:11.185542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.642906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-246n2\" ","response":"range_response_count:1 size:4653"} {"level":"warn","ts":"2026-04-20T10:33:11.185681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:33:10.667864Z","time spent":"517.806251ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T10:33:11.185742Z","caller":"traceutil/trace.go:171","msg":"trace[74854324] transaction","detail":"{read_only:false; response_revision:8937; number_of_response:1; }","duration":"416.006494ms","start":"2026-04-20T10:33:10.769708Z","end":"2026-04-20T10:33:11.185715Z","steps":["trace[74854324] 'process raft request' (duration: 415.38558ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:11.185762Z","caller":"traceutil/trace.go:171","msg":"trace[1376448624] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-246n2; range_end:; response_count:1; response_revision:8937; }","duration":"424.894432ms","start":"2026-04-20T10:33:10.760818Z","end":"2026-04-20T10:33:11.185713Z","steps":["trace[1376448624] 'agreement among raft nodes before linearized reading' (duration: 424.387211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:33:11.185873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:33:10.760807Z","time spent":"425.020265ms","remote":"127.0.0.1:39852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4675,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-246n2\" "} {"level":"warn","ts":"2026-04-20T10:33:11.185903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:33:10.769691Z","time spent":"416.125966ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:33:11.190852Z","caller":"traceutil/trace.go:171","msg":"trace[584509220] transaction","detail":"{read_only:false; response_revision:8938; number_of_response:1; }","duration":"346.55318ms","start":"2026-04-20T10:33:10.844272Z","end":"2026-04-20T10:33:11.190825Z","steps":["trace[584509220] 'process raft request' (duration: 346.386756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:33:11.190982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:33:10.844249Z","time spent":"346.655162ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:33:11.191056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.048144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-20T10:33:11.191111Z","caller":"traceutil/trace.go:171","msg":"trace[463738487] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8938; }","duration":"162.211288ms","start":"2026-04-20T10:33:11.028885Z","end":"2026-04-20T10:33:11.191097Z","steps":["trace[463738487] 'agreement among raft nodes before linearized reading' (duration: 161.971072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:33:11.974953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.974305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:33:11.975134Z","caller":"traceutil/trace.go:171","msg":"trace[1634107147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8943; }","duration":"235.247881ms","start":"2026-04-20T10:33:11.739863Z","end":"2026-04-20T10:33:11.975111Z","steps":["trace[1634107147] 'range keys from in-memory index tree' (duration: 234.860373ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:23.417109Z","caller":"traceutil/trace.go:171","msg":"trace[2025212668] transaction","detail":"{read_only:false; response_revision:9038; number_of_response:1; }","duration":"156.343977ms","start":"2026-04-20T10:33:23.260737Z","end":"2026-04-20T10:33:23.417081Z","steps":["trace[2025212668] 'process raft request' (duration: 156.183433ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:23.417623Z","caller":"traceutil/trace.go:171","msg":"trace[1361206427] linearizableReadLoop","detail":"{readStateIndex:9363; appliedIndex:9363; }","duration":"151.405606ms","start":"2026-04-20T10:33:23.266205Z","end":"2026-04-20T10:33:23.41761Z","steps":["trace[1361206427] 'read index received' (duration: 150.634628ms)","trace[1361206427] 'applied index is now lower than readState.Index' (duration: 768.648µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:33:23.417802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.57862ms","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-20T10:33:23.417838Z","caller":"traceutil/trace.go:171","msg":"trace[1432518465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9038; }","duration":"151.636372ms","start":"2026-04-20T10:33:23.266193Z","end":"2026-04-20T10:33:23.417829Z","steps":["trace[1432518465] 'agreement among raft nodes before linearized reading' (duration: 151.484988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:33:23.418095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.244696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-20T10:33:23.41812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.329209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4qggz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T10:33:23.418229Z","caller":"traceutil/trace.go:171","msg":"trace[1511414026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9038; }","duration":"131.387479ms","start":"2026-04-20T10:33:23.286807Z","end":"2026-04-20T10:33:23.418195Z","steps":["trace[1511414026] 'agreement among raft nodes before linearized reading' (duration: 131.040061ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:23.418253Z","caller":"traceutil/trace.go:171","msg":"trace[225326804] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4qggz; range_end:; response_count:1; response_revision:9038; }","duration":"132.529774ms","start":"2026-04-20T10:33:23.285683Z","end":"2026-04-20T10:33:23.418212Z","steps":["trace[225326804] 'agreement among raft nodes before linearized reading' (duration: 132.136465ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:33:25.65849Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b5.wal"} {"level":"info","ts":"2026-04-20T10:34:23.186345Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e98569549cc430c","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T10:34:23.196996Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-04-20T10:34:23.197092Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-04-20T10:34:29.898091Z","caller":"traceutil/trace.go:171","msg":"trace[377085340] transaction","detail":"{read_only:false; response_revision:9692; number_of_response:1; }","duration":"100.915537ms","start":"2026-04-20T10:34:29.797155Z","end":"2026-04-20T10:34:29.89807Z","steps":["trace[377085340] 'process raft request' (duration: 100.745403ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:29.918558Z","caller":"traceutil/trace.go:171","msg":"trace[892900800] transaction","detail":"{read_only:false; response_revision:9693; number_of_response:1; }","duration":"118.201023ms","start":"2026-04-20T10:34:29.800329Z","end":"2026-04-20T10:34:29.91853Z","steps":["trace[892900800] 'process raft request' (duration: 118.004029ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:29.918719Z","caller":"traceutil/trace.go:171","msg":"trace[1297609583] linearizableReadLoop","detail":"{readStateIndex:10037; appliedIndex:10034; }","duration":"112.906205ms","start":"2026-04-20T10:34:29.805783Z","end":"2026-04-20T10:34:29.91869Z","steps":["trace[1297609583] 'read index received' (duration: 92.214222ms)","trace[1297609583] 'applied index is now lower than readState.Index' (duration: 20.689083ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:34:29.91877Z","caller":"traceutil/trace.go:171","msg":"trace[810320295] transaction","detail":"{read_only:false; response_revision:9694; number_of_response:1; }","duration":"116.938495ms","start":"2026-04-20T10:34:29.801821Z","end":"2026-04-20T10:34:29.918759Z","steps":["trace[810320295] 'process raft request' (duration: 116.663819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:29.919095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.281343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T10:34:29.919174Z","caller":"traceutil/trace.go:171","msg":"trace[490269393] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:9694; }","duration":"113.400956ms","start":"2026-04-20T10:34:29.805756Z","end":"2026-04-20T10:34:29.919156Z","steps":["trace[490269393] 'agreement among raft nodes before linearized reading' (duration: 113.085859ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:30.086579Z","caller":"traceutil/trace.go:171","msg":"trace[157642654] linearizableReadLoop","detail":"{readStateIndex:10038; appliedIndex:10037; }","duration":"162.484504ms","start":"2026-04-20T10:34:29.924036Z","end":"2026-04-20T10:34:30.086521Z","steps":["trace[157642654] 'read index received' (duration: 78.68239ms)","trace[157642654] 'applied index is now lower than readState.Index' (duration: 83.799994ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:34:30.087193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.123528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291504"} {"level":"info","ts":"2026-04-20T10:34:30.087239Z","caller":"traceutil/trace.go:171","msg":"trace[1064958850] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9694; }","duration":"163.22067ms","start":"2026-04-20T10:34:29.924007Z","end":"2026-04-20T10:34:30.087227Z","steps":["trace[1064958850] 'agreement among raft nodes before linearized reading' (duration: 162.677178ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:36:16.630374Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8035} {"level":"info","ts":"2026-04-20T10:36:16.705453Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8035,"took":"73.086192ms","hash":2941502429,"current-db-size-bytes":56061952,"current-db-size":"56 MB","current-db-size-in-use-bytes":28278784,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-20T10:36:16.705528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2941502429,"revision":8035,"compact-revision":5880} {"level":"info","ts":"2026-04-20T10:38:18.106528Z","caller":"traceutil/trace.go:171","msg":"trace[1730309085] transaction","detail":"{read_only:false; response_revision:11097; number_of_response:1; }","duration":"126.814902ms","start":"2026-04-20T10:38:17.979682Z","end":"2026-04-20T10:38:18.106497Z","steps":["trace[1730309085] 'process raft request' (duration: 105.713639ms)","trace[1730309085] 'compare' (duration: 20.93786ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:38:18.106533Z","caller":"traceutil/trace.go:171","msg":"trace[266137834] linearizableReadLoop","detail":"{readStateIndex:11494; appliedIndex:11493; }","duration":"102.109059ms","start":"2026-04-20T10:38:18.004408Z","end":"2026-04-20T10:38:18.106517Z","steps":["trace[266137834] 'read index received' (duration: 81.075337ms)","trace[266137834] 'applied index is now lower than readState.Index' (duration: 21.032382ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:38:18.106949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.518988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:38:18.107032Z","caller":"traceutil/trace.go:171","msg":"trace[1697191711] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:11098; }","duration":"102.658511ms","start":"2026-04-20T10:38:18.004354Z","end":"2026-04-20T10:38:18.107013Z","steps":["trace[1697191711] 'agreement among raft nodes before linearized reading' (duration: 102.18325ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:38:38.611158Z","caller":"traceutil/trace.go:171","msg":"trace[81282868] transaction","detail":"{read_only:false; response_revision:11301; number_of_response:1; }","duration":"106.162609ms","start":"2026-04-20T10:38:38.504968Z","end":"2026-04-20T10:38:38.61113Z","steps":["trace[81282868] 'process raft request' (duration: 97.628068ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:41:16.637514Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10426} {"level":"info","ts":"2026-04-20T10:41:16.725767Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10426,"took":"86.272233ms","hash":4024722503,"current-db-size-bytes":56061952,"current-db-size":"56 MB","current-db-size-in-use-bytes":22581248,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-20T10:41:16.725911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4024722503,"revision":10426,"compact-revision":8035} {"level":"info","ts":"2026-04-20T10:43:53.183009Z","caller":"traceutil/trace.go:171","msg":"trace[1546084728] transaction","detail":"{read_only:false; response_revision:12986; number_of_response:1; }","duration":"151.334392ms","start":"2026-04-20T10:43:53.031644Z","end":"2026-04-20T10:43:53.182979Z","steps":["trace[1546084728] 'process raft request' (duration: 151.099427ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:53.183153Z","caller":"traceutil/trace.go:171","msg":"trace[1073047022] transaction","detail":"{read_only:false; response_revision:12987; number_of_response:1; }","duration":"149.172243ms","start":"2026-04-20T10:43:53.033943Z","end":"2026-04-20T10:43:53.183115Z","steps":["trace[1073047022] 'process raft request' (duration: 148.966318ms)"],"step_count":1}