{"level":"warn","ts":"2026-08-13T07:54:53.8088Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T07:54:53.808906Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.14: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.19.213.14:2380","--initial-cluster=instance=https://199.19.213.14:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.14:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.14: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-08-13T07:54:53.809105Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T07:54:53.809121Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.14:2380"]} {"level":"info","ts":"2026-08-13T07:54:53.809141Z","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-08-13T07:54:53.81001Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.14:2379"]} {"level":"info","ts":"2026-08-13T07:54:53.810133Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.14:2380"],"listen-peer-urls":["https://199.19.213.14:2380"],"advertise-client-urls":["https://199.19.213.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.14: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-08-13T07:54:53.814606Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.169833ms"} {"level":"info","ts":"2026-08-13T07:54:53.82193Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"329f95029c2a91ed","cluster-id":"5fd85759a04d0f9f"} {"level":"info","ts":"2026-08-13T07:54:53.822187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T07:54:53.82223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became follower at term 0"} {"level":"info","ts":"2026-08-13T07:54:53.822313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 329f95029c2a91ed [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T07:54:53.822391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became follower at term 1"} {"level":"info","ts":"2026-08-13T07:54:53.822617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed switched to configuration voters=(3647798061635899885)"} {"level":"warn","ts":"2026-08-13T07:54:53.831173Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T07:54:53.833073Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T07:54:53.834256Z","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-08-13T07:54:53.838312Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"329f95029c2a91ed","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T07:54:53.838474Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"329f95029c2a91ed","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T07:54:53.83861Z","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-08-13T07:54:53.838652Z","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-08-13T07:54:53.838663Z","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-08-13T07:54:53.840462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed switched to configuration voters=(3647798061635899885)"} {"level":"info","ts":"2026-08-13T07:54:53.840546Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5fd85759a04d0f9f","local-member-id":"329f95029c2a91ed","added-peer-id":"329f95029c2a91ed","added-peer-peer-urls":["https://199.19.213.14:2380"]} {"level":"info","ts":"2026-08-13T07:54:53.842549Z","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-08-13T07:54:53.842778Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.14:2380"} {"level":"info","ts":"2026-08-13T07:54:53.842816Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.14:2380"} {"level":"info","ts":"2026-08-13T07:54:53.843161Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"329f95029c2a91ed","initial-advertise-peer-urls":["https://199.19.213.14:2380"],"listen-peer-urls":["https://199.19.213.14:2380"],"advertise-client-urls":["https://199.19.213.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T07:54:53.843221Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T07:54:53.9231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T07:54:53.923162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T07:54:53.923237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed received MsgPreVoteResp from 329f95029c2a91ed at term 1"} {"level":"info","ts":"2026-08-13T07:54:53.923396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became candidate at term 2"} {"level":"info","ts":"2026-08-13T07:54:53.923463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed received MsgVoteResp from 329f95029c2a91ed at term 2"} {"level":"info","ts":"2026-08-13T07:54:53.923529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became leader at term 2"} {"level":"info","ts":"2026-08-13T07:54:53.923596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 329f95029c2a91ed elected leader 329f95029c2a91ed at term 2"} {"level":"info","ts":"2026-08-13T07:54:53.924652Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T07:54:53.925407Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"329f95029c2a91ed","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.14:2379]}","request-path":"/0/members/329f95029c2a91ed/attributes","cluster-id":"5fd85759a04d0f9f","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T07:54:53.925682Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T07:54:53.925749Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T07:54:53.925833Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5fd85759a04d0f9f","local-member-id":"329f95029c2a91ed","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T07:54:53.926124Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T07:54:53.926225Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T07:54:53.925927Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T07:54:53.926303Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T07:54:53.927664Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-13T07:54:53.929294Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.14:2379"} {"level":"info","ts":"2026-08-13T07:54:55.670794Z","caller":"traceutil/trace.go:171","msg":"trace[374197234] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"273.515482ms","start":"2026-08-13T07:54:55.397243Z","end":"2026-08-13T07:54:55.670759Z","steps":["trace[374197234] 'read index received' (duration: 273.305538ms)","trace[374197234] 'applied index is now lower than readState.Index' (duration: 208.854µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T07:54:55.670861Z","caller":"traceutil/trace.go:171","msg":"trace[1227945812] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"273.812898ms","start":"2026-08-13T07:54:55.396994Z","end":"2026-08-13T07:54:55.670807Z","steps":["trace[1227945812] 'process raft request' (duration: 273.582893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:54:55.671068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.792448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T07:54:55.671174Z","caller":"traceutil/trace.go:171","msg":"trace[1023004071] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:2; }","duration":"273.930311ms","start":"2026-08-13T07:54:55.397228Z","end":"2026-08-13T07:54:55.671159Z","steps":["trace[1023004071] 'agreement among raft nodes before linearized reading' (duration: 273.661765ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:54:55.724137Z","caller":"traceutil/trace.go:171","msg":"trace[1467117613] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"258.373331ms","start":"2026-08-13T07:54:55.465737Z","end":"2026-08-13T07:54:55.72411Z","steps":["trace[1467117613] 'process raft request' (duration: 258.172978ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:54:55.724243Z","caller":"traceutil/trace.go:171","msg":"trace[1911389629] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"255.367872ms","start":"2026-08-13T07:54:55.468855Z","end":"2026-08-13T07:54:55.724223Z","steps":["trace[1911389629] 'process raft request' (duration: 255.274041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:54:55.724312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.437174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T07:54:55.724373Z","caller":"traceutil/trace.go:171","msg":"trace[193124481] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"316.536996ms","start":"2026-08-13T07:54:55.407825Z","end":"2026-08-13T07:54:55.724362Z","steps":["trace[193124481] 'agreement among raft nodes before linearized reading' (duration: 316.404324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:54:55.724409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:54:55.407813Z","time spent":"316.587858ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-08-13T07:54:55.724237Z","caller":"traceutil/trace.go:171","msg":"trace[359585071] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"256.786149ms","start":"2026-08-13T07:54:55.467393Z","end":"2026-08-13T07:54:55.724179Z","steps":["trace[359585071] 'process raft request' (duration: 256.687987ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:54:55.724512Z","caller":"traceutil/trace.go:171","msg":"trace[1531938557] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"256.343381ms","start":"2026-08-13T07:54:55.468161Z","end":"2026-08-13T07:54:55.724504Z","steps":["trace[1531938557] 'process raft request' (duration: 255.952432ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:54:55.724727Z","caller":"traceutil/trace.go:171","msg":"trace[553259129] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"238.563208ms","start":"2026-08-13T07:54:55.486152Z","end":"2026-08-13T07:54:55.724715Z","steps":["trace[553259129] 'process raft request' (duration: 237.998847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:54:55.724848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.147394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T07:54:55.724921Z","caller":"traceutil/trace.go:171","msg":"trace[127304043] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"281.259186ms","start":"2026-08-13T07:54:55.443623Z","end":"2026-08-13T07:54:55.724882Z","steps":["trace[127304043] 'agreement among raft nodes before linearized reading' (duration: 281.156244ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:54:55.725012Z","caller":"traceutil/trace.go:171","msg":"trace[1959190256] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"258.260179ms","start":"2026-08-13T07:54:55.466742Z","end":"2026-08-13T07:54:55.725002Z","steps":["trace[1959190256] 'process raft request' (duration: 257.31335ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:54:55.724457Z","caller":"traceutil/trace.go:171","msg":"trace[1725096516] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"256.453603ms","start":"2026-08-13T07:54:55.467953Z","end":"2026-08-13T07:54:55.724406Z","steps":["trace[1725096516] 'process raft request' (duration: 256.146056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:54:55.725105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.761318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T07:54:55.725134Z","caller":"traceutil/trace.go:171","msg":"trace[340543591] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"274.816438ms","start":"2026-08-13T07:54:55.45031Z","end":"2026-08-13T07:54:55.725126Z","steps":["trace[340543591] 'agreement among raft nodes before linearized reading' (duration: 274.772927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:54:55.725121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.721106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-13T07:54:55.725217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.352852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T07:54:55.725209Z","caller":"traceutil/trace.go:171","msg":"trace[1238541567] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"274.825717ms","start":"2026-08-13T07:54:55.450358Z","end":"2026-08-13T07:54:55.725183Z","steps":["trace[1238541567] 'agreement among raft nodes before linearized reading' (duration: 274.686774ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:54:55.725246Z","caller":"traceutil/trace.go:171","msg":"trace[1942543550] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"182.400972ms","start":"2026-08-13T07:54:55.542837Z","end":"2026-08-13T07:54:55.725238Z","steps":["trace[1942543550] 'agreement among raft nodes before linearized reading' (duration: 182.358372ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:54:55.725365Z","caller":"traceutil/trace.go:171","msg":"trace[1927992660] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"238.845274ms","start":"2026-08-13T07:54:55.486508Z","end":"2026-08-13T07:54:55.725353Z","steps":["trace[1927992660] 'process raft request' (duration: 237.67608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:54:55.725444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.529514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-13T07:54:55.725396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.360661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T07:54:55.725481Z","caller":"traceutil/trace.go:171","msg":"trace[867511817] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"256.590786ms","start":"2026-08-13T07:54:55.468879Z","end":"2026-08-13T07:54:55.72547Z","steps":["trace[867511817] 'agreement among raft nodes before linearized reading' (duration: 256.538965ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:54:55.725508Z","caller":"traceutil/trace.go:171","msg":"trace[1488597730] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"256.504984ms","start":"2026-08-13T07:54:55.468984Z","end":"2026-08-13T07:54:55.725489Z","steps":["trace[1488597730] 'agreement among raft nodes before linearized reading' (duration: 256.30074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:54:55.726121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.538475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.14\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T07:54:55.72648Z","caller":"traceutil/trace.go:171","msg":"trace[249615381] range","detail":"{range_begin:/registry/masterleases/199.19.213.14; range_end:; response_count:0; response_revision:10; }","duration":"262.619105ms","start":"2026-08-13T07:54:55.463537Z","end":"2026-08-13T07:54:55.726156Z","steps":["trace[249615381] 'agreement among raft nodes before linearized reading' (duration: 261.210907ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:29.675001Z","caller":"traceutil/trace.go:171","msg":"trace[702297432] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:513; }","duration":"163.915587ms","start":"2026-08-13T07:55:29.511063Z","end":"2026-08-13T07:55:29.674979Z","steps":["trace[702297432] 'read index received' (duration: 163.679983ms)","trace[702297432] 'applied index is now lower than readState.Index' (duration: 234.274µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T07:55:29.675112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.034909ms","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-08-13T07:55:29.675159Z","caller":"traceutil/trace.go:171","msg":"trace[540214081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"164.11667ms","start":"2026-08-13T07:55:29.511027Z","end":"2026-08-13T07:55:29.675144Z","steps":["trace[540214081] 'agreement among raft nodes before linearized reading' (duration: 164.036349ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:29.675131Z","caller":"traceutil/trace.go:171","msg":"trace[1113340131] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"248.001805ms","start":"2026-08-13T07:55:29.427036Z","end":"2026-08-13T07:55:29.675038Z","steps":["trace[1113340131] 'process raft request' (duration: 247.805102ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:30.727781Z","caller":"traceutil/trace.go:171","msg":"trace[484144445] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"157.035884ms","start":"2026-08-13T07:55:30.570729Z","end":"2026-08-13T07:55:30.727764Z","steps":["trace[484144445] 'process raft request' (duration: 156.949993ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:30.732846Z","caller":"traceutil/trace.go:171","msg":"trace[1618752501] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"155.217349ms","start":"2026-08-13T07:55:30.57759Z","end":"2026-08-13T07:55:30.732807Z","steps":["trace[1618752501] 'process raft request' (duration: 155.051916ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:30.733006Z","caller":"traceutil/trace.go:171","msg":"trace[739389534] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:519; }","duration":"150.222566ms","start":"2026-08-13T07:55:30.582754Z","end":"2026-08-13T07:55:30.732976Z","steps":["trace[739389534] 'read index received' (duration: 145.388934ms)","trace[739389534] 'applied index is now lower than readState.Index' (duration: 4.833052ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T07:55:30.733091Z","caller":"traceutil/trace.go:171","msg":"trace[609279399] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"155.411603ms","start":"2026-08-13T07:55:30.577673Z","end":"2026-08-13T07:55:30.733084Z","steps":["trace[609279399] 'process raft request' (duration: 155.044465ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:30.733106Z","caller":"traceutil/trace.go:171","msg":"trace[2083778862] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"142.457599ms","start":"2026-08-13T07:55:30.590632Z","end":"2026-08-13T07:55:30.73309Z","steps":["trace[2083778862] 'process raft request' (duration: 142.240045ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:30.733174Z","caller":"traceutil/trace.go:171","msg":"trace[1894078065] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"133.500609ms","start":"2026-08-13T07:55:30.599669Z","end":"2026-08-13T07:55:30.733169Z","steps":["trace[1894078065] 'process raft request' (duration: 133.250524ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:30.733222Z","caller":"traceutil/trace.go:171","msg":"trace[343255164] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"106.080382ms","start":"2026-08-13T07:55:30.627137Z","end":"2026-08-13T07:55:30.733217Z","steps":["trace[343255164] 'process raft request' (duration: 105.813097ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:30.733252Z","caller":"traceutil/trace.go:171","msg":"trace[32643804] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"155.338781ms","start":"2026-08-13T07:55:30.577901Z","end":"2026-08-13T07:55:30.733239Z","steps":["trace[32643804] 'process raft request' (duration: 154.840022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:55:30.733302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.535482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T07:55:30.733323Z","caller":"traceutil/trace.go:171","msg":"trace[155613678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"150.596692ms","start":"2026-08-13T07:55:30.582721Z","end":"2026-08-13T07:55:30.733318Z","steps":["trace[155613678] 'agreement among raft nodes before linearized reading' (duration: 150.53522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:55:30.733476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.87809ms","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-08-13T07:55:30.733495Z","caller":"traceutil/trace.go:171","msg":"trace[1922065222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"146.917404ms","start":"2026-08-13T07:55:30.586573Z","end":"2026-08-13T07:55:30.73349Z","steps":["trace[1922065222] 'agreement among raft nodes before linearized reading' (duration: 146.879142ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:30.73353Z","caller":"traceutil/trace.go:171","msg":"trace[751145077] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"151.772624ms","start":"2026-08-13T07:55:30.581745Z","end":"2026-08-13T07:55:30.733518Z","steps":["trace[751145077] 'process raft request' (duration: 151.01789ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:30.733625Z","caller":"traceutil/trace.go:171","msg":"trace[1748875132] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"150.893908ms","start":"2026-08-13T07:55:30.582726Z","end":"2026-08-13T07:55:30.73362Z","steps":["trace[1748875132] 'process raft request' (duration: 150.098202ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:30.733663Z","caller":"traceutil/trace.go:171","msg":"trace[703767449] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"145.507156ms","start":"2026-08-13T07:55:30.588134Z","end":"2026-08-13T07:55:30.733641Z","steps":["trace[703767449] 'process raft request' (duration: 144.709991ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:30.733693Z","caller":"traceutil/trace.go:171","msg":"trace[225247911] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"151.880776ms","start":"2026-08-13T07:55:30.581808Z","end":"2026-08-13T07:55:30.733688Z","steps":["trace[225247911] 'process raft request' (duration: 150.99116ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:31.300834Z","caller":"traceutil/trace.go:171","msg":"trace[949821317] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"270.982908ms","start":"2026-08-13T07:55:31.029828Z","end":"2026-08-13T07:55:31.300811Z","steps":["trace[949821317] 'process raft request' (duration: 270.837986ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:31.300972Z","caller":"traceutil/trace.go:171","msg":"trace[1429957142] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"317.940194ms","start":"2026-08-13T07:55:30.982978Z","end":"2026-08-13T07:55:31.300918Z","steps":["trace[1429957142] 'process raft request' (duration: 306.857066ms)","trace[1429957142] 'compare' (duration: 10.58263ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T07:55:31.301708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:55:30.982956Z","time spent":"318.115068ms","remote":"127.0.0.1:39522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T07:55:37.793132Z","caller":"traceutil/trace.go:171","msg":"trace[750587439] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"206.596039ms","start":"2026-08-13T07:55:37.586505Z","end":"2026-08-13T07:55:37.793101Z","steps":["trace[750587439] 'process raft request' (duration: 205.438208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:55:38.486366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.005118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T07:55:38.486714Z","caller":"traceutil/trace.go:171","msg":"trace[2055183311] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"632.943778ms","start":"2026-08-13T07:55:37.853752Z","end":"2026-08-13T07:55:38.486696Z","steps":["trace[2055183311] 'process raft request' (duration: 632.864797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:55:38.486838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:55:37.853705Z","time spent":"633.10026ms","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T07:55:38.486866Z","caller":"traceutil/trace.go:171","msg":"trace[929294221] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"688.915416ms","start":"2026-08-13T07:55:37.797928Z","end":"2026-08-13T07:55:38.486843Z","steps":["trace[929294221] 'process raft request' (duration: 157.162264ms)","trace[929294221] 'compare' (duration: 530.699802ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T07:55:38.486969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:55:37.797906Z","time spent":"689.011787ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T07:55:38.792819Z","caller":"traceutil/trace.go:171","msg":"trace[1446797601] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"206.409873ms","start":"2026-08-13T07:55:38.586384Z","end":"2026-08-13T07:55:38.792794Z","steps":["trace[1446797601] 'read index received' (duration: 201.251256ms)","trace[1446797601] 'applied index is now lower than readState.Index' (duration: 5.156977ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T07:55:38.792866Z","caller":"traceutil/trace.go:171","msg":"trace[625497384] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"290.18167ms","start":"2026-08-13T07:55:38.502661Z","end":"2026-08-13T07:55:38.792843Z","steps":["trace[625497384] 'process raft request' (duration: 285.059934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:55:38.793002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.599147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T07:55:38.793064Z","caller":"traceutil/trace.go:171","msg":"trace[783031704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"206.694617ms","start":"2026-08-13T07:55:38.58634Z","end":"2026-08-13T07:55:38.793035Z","steps":["trace[783031704] 'agreement among raft nodes before linearized reading' (duration: 206.604956ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:55:54.324461Z","caller":"traceutil/trace.go:171","msg":"trace[1536664198] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"155.659982ms","start":"2026-08-13T07:55:54.168775Z","end":"2026-08-13T07:55:54.324435Z","steps":["trace[1536664198] 'process raft request' (duration: 155.499199ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:56:34.971794Z","caller":"traceutil/trace.go:171","msg":"trace[609363768] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"385.807401ms","start":"2026-08-13T07:56:34.585967Z","end":"2026-08-13T07:56:34.971774Z","steps":["trace[609363768] 'read index received' (duration: 385.632949ms)","trace[609363768] 'applied index is now lower than readState.Index' (duration: 173.852µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T07:56:34.971885Z","caller":"traceutil/trace.go:171","msg":"trace[1907040301] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"444.737733ms","start":"2026-08-13T07:56:34.527139Z","end":"2026-08-13T07:56:34.971876Z","steps":["trace[1907040301] 'process raft request' (duration: 444.522229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:56:34.97198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:56:34.527121Z","time spent":"444.785505ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T07:56:34.97205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.767831ms","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-08-13T07:56:34.972098Z","caller":"traceutil/trace.go:171","msg":"trace[2067891309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1263; }","duration":"143.860992ms","start":"2026-08-13T07:56:34.828227Z","end":"2026-08-13T07:56:34.972088Z","steps":["trace[2067891309] 'agreement among raft nodes before linearized reading' (duration: 143.71448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:56:34.972202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.199969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T07:56:34.972344Z","caller":"traceutil/trace.go:171","msg":"trace[104965766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"386.359991ms","start":"2026-08-13T07:56:34.58593Z","end":"2026-08-13T07:56:34.97229Z","steps":["trace[104965766] 'agreement among raft nodes before linearized reading' (duration: 386.147997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:56:34.972408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:56:34.585915Z","time spent":"386.482995ms","remote":"127.0.0.1:39490","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-08-13T07:57:13.036085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.035436ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-13T07:57:13.036573Z","caller":"traceutil/trace.go:171","msg":"trace[744057494] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1914; }","duration":"297.768715ms","start":"2026-08-13T07:57:12.738779Z","end":"2026-08-13T07:57:13.036548Z","steps":["trace[744057494] 'read index received' (duration: 148.261792ms)","trace[744057494] 'applied index is now lower than readState.Index' (duration: 149.505533ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T07:57:13.03678Z","caller":"traceutil/trace.go:171","msg":"trace[412664923] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"500.344748ms","start":"2026-08-13T07:57:12.536382Z","end":"2026-08-13T07:57:13.036727Z","steps":["trace[412664923] 'process raft request' (duration: 499.875461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:13.036969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.17745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-13T07:57:13.03706Z","caller":"traceutil/trace.go:171","msg":"trace[921614618] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1844; }","duration":"298.312095ms","start":"2026-08-13T07:57:12.738725Z","end":"2026-08-13T07:57:13.037037Z","steps":["trace[921614618] 'agreement among raft nodes before linearized reading' (duration: 298.083657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:13.036987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:12.536357Z","time spent":"500.521034ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T07:57:13.037308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.390901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T07:57:13.037416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.384185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c6681de-70d5-48a1-a37f-3df9082d0d0e\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-13T07:57:13.037413Z","caller":"traceutil/trace.go:171","msg":"trace[1473539262] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1844; }","duration":"242.558637ms","start":"2026-08-13T07:57:12.794834Z","end":"2026-08-13T07:57:13.037392Z","steps":["trace[1473539262] 'agreement among raft nodes before linearized reading' (duration: 242.371ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:13.037562Z","caller":"traceutil/trace.go:171","msg":"trace[1189704015] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2c6681de-70d5-48a1-a37f-3df9082d0d0e; range_end:; response_count:1; response_revision:1844; }","duration":"230.534851ms","start":"2026-08-13T07:57:12.806967Z","end":"2026-08-13T07:57:13.037502Z","steps":["trace[1189704015] 'agreement among raft nodes before linearized reading' (duration: 230.266632ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:13.534806Z","caller":"traceutil/trace.go:171","msg":"trace[49541830] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1920; }","duration":"156.876116ms","start":"2026-08-13T07:57:13.377893Z","end":"2026-08-13T07:57:13.53477Z","steps":["trace[49541830] 'read index received' (duration: 156.72308ms)","trace[49541830] 'applied index is now lower than readState.Index' (duration: 151.215µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T07:57:13.534846Z","caller":"traceutil/trace.go:171","msg":"trace[306198929] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"192.756364ms","start":"2026-08-13T07:57:13.342055Z","end":"2026-08-13T07:57:13.534811Z","steps":["trace[306198929] 'process raft request' (duration: 192.571096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:13.535178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.26704ms","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-08-13T07:57:13.535262Z","caller":"traceutil/trace.go:171","msg":"trace[279421517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1849; }","duration":"157.413997ms","start":"2026-08-13T07:57:13.37783Z","end":"2026-08-13T07:57:13.535244Z","steps":["trace[279421517] 'agreement among raft nodes before linearized reading' (duration: 157.066864ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:21.060247Z","caller":"traceutil/trace.go:171","msg":"trace[1173636926] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"181.278874ms","start":"2026-08-13T07:57:20.878933Z","end":"2026-08-13T07:57:21.060212Z","steps":["trace[1173636926] 'process raft request' (duration: 181.013624ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:28.542743Z","caller":"traceutil/trace.go:171","msg":"trace[1321555482] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"160.462842ms","start":"2026-08-13T07:57:28.382254Z","end":"2026-08-13T07:57:28.542716Z","steps":["trace[1321555482] 'process raft request' (duration: 160.294917ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:28.544061Z","caller":"traceutil/trace.go:171","msg":"trace[1150966895] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"160.67331ms","start":"2026-08-13T07:57:28.383377Z","end":"2026-08-13T07:57:28.544051Z","steps":["trace[1150966895] 'process raft request' (duration: 160.368779ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:28.544181Z","caller":"traceutil/trace.go:171","msg":"trace[2032159821] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"131.43156ms","start":"2026-08-13T07:57:28.412742Z","end":"2026-08-13T07:57:28.544174Z","steps":["trace[2032159821] 'process raft request' (duration: 131.051946ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:46.479774Z","caller":"traceutil/trace.go:171","msg":"trace[1987003470] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"121.089001ms","start":"2026-08-13T07:57:46.358662Z","end":"2026-08-13T07:57:46.479751Z","steps":["trace[1987003470] 'process raft request' (duration: 120.940397ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:47.362277Z","caller":"traceutil/trace.go:171","msg":"trace[826739122] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"212.978182ms","start":"2026-08-13T07:57:47.149198Z","end":"2026-08-13T07:57:47.362176Z","steps":["trace[826739122] 'process raft request' (duration: 212.839247ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:47.362677Z","caller":"traceutil/trace.go:171","msg":"trace[1238977443] linearizableReadLoop","detail":"{readStateIndex:2164; appliedIndex:2164; }","duration":"105.40143ms","start":"2026-08-13T07:57:47.257262Z","end":"2026-08-13T07:57:47.362663Z","steps":["trace[1238977443] 'read index received' (duration: 105.395789ms)","trace[1238977443] 'applied index is now lower than readState.Index' (duration: 4.791µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T07:57:47.363179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.903056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.14\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-13T07:57:47.36327Z","caller":"traceutil/trace.go:171","msg":"trace[365285386] range","detail":"{range_begin:/registry/masterleases/199.19.213.14; range_end:; response_count:1; response_revision:2086; }","duration":"106.071012ms","start":"2026-08-13T07:57:47.257181Z","end":"2026-08-13T07:57:47.363252Z","steps":["trace[365285386] 'agreement among raft nodes before linearized reading' (duration: 105.575415ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:47.493262Z","caller":"traceutil/trace.go:171","msg":"trace[402949636] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"227.523233ms","start":"2026-08-13T07:57:47.265701Z","end":"2026-08-13T07:57:47.493225Z","steps":["trace[402949636] 'process raft request' (duration: 166.588155ms)","trace[402949636] 'compare' (duration: 60.734301ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T07:57:47.49334Z","caller":"traceutil/trace.go:171","msg":"trace[1913231489] linearizableReadLoop","detail":"{readStateIndex:2165; appliedIndex:2164; }","duration":"130.58349ms","start":"2026-08-13T07:57:47.362733Z","end":"2026-08-13T07:57:47.493316Z","steps":["trace[1913231489] 'read index received' (duration: 69.57661ms)","trace[1913231489] 'applied index is now lower than readState.Index' (duration: 61.00416ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T07:57:47.493691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.423851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-08-13T07:57:47.493627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.603839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-08-13T07:57:47.493854Z","caller":"traceutil/trace.go:171","msg":"trace[862408031] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2087; }","duration":"175.894039ms","start":"2026-08-13T07:57:47.31793Z","end":"2026-08-13T07:57:47.493824Z","steps":["trace[862408031] 'agreement among raft nodes before linearized reading' (duration: 175.430983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:47.493907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.99238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-13T07:57:47.493969Z","caller":"traceutil/trace.go:171","msg":"trace[1070834533] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2087; }","duration":"226.089285ms","start":"2026-08-13T07:57:47.267864Z","end":"2026-08-13T07:57:47.493953Z","steps":["trace[1070834533] 'agreement among raft nodes before linearized reading' (duration: 225.939079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:47.493774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.446347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-13T07:57:47.49412Z","caller":"traceutil/trace.go:171","msg":"trace[2021273375] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2087; }","duration":"112.823319ms","start":"2026-08-13T07:57:47.381282Z","end":"2026-08-13T07:57:47.494105Z","steps":["trace[2021273375] 'agreement among raft nodes before linearized reading' (duration: 112.379365ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:47.494335Z","caller":"traceutil/trace.go:171","msg":"trace[765137483] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2087; }","duration":"201.539746ms","start":"2026-08-13T07:57:47.292204Z","end":"2026-08-13T07:57:47.493744Z","steps":["trace[765137483] 'agreement among raft nodes before linearized reading' (duration: 201.313668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:47.95886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.470755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T07:57:47.959268Z","caller":"traceutil/trace.go:171","msg":"trace[1788007243] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2167; }","duration":"453.293416ms","start":"2026-08-13T07:57:47.505954Z","end":"2026-08-13T07:57:47.959248Z","steps":["trace[1788007243] 'read index received' (duration: 176.2098ms)","trace[1788007243] 'applied index is now lower than readState.Index' (duration: 277.078876ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T07:57:47.959382Z","caller":"traceutil/trace.go:171","msg":"trace[1398684650] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"460.090115ms","start":"2026-08-13T07:57:47.499255Z","end":"2026-08-13T07:57:47.959346Z","steps":["trace[1398684650] 'process raft request' (duration: 459.728503ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:47.959446Z","caller":"traceutil/trace.go:171","msg":"trace[45012583] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"458.619836ms","start":"2026-08-13T07:57:47.500815Z","end":"2026-08-13T07:57:47.959435Z","steps":["trace[45012583] 'process raft request' (duration: 458.384079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:47.959501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:47.499228Z","time spent":"460.208059ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T07:57:47.959406Z","caller":"traceutil/trace.go:171","msg":"trace[404153130] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"460.114407ms","start":"2026-08-13T07:57:47.499282Z","end":"2026-08-13T07:57:47.959396Z","steps":["trace[404153130] 'process raft request' (duration: 459.881819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:47.960372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.412084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-08-13T07:57:47.960361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:47.499267Z","time spent":"461.003407ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T07:57:47.960417Z","caller":"traceutil/trace.go:171","msg":"trace[276904658] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2092; }","duration":"454.473966ms","start":"2026-08-13T07:57:47.505921Z","end":"2026-08-13T07:57:47.960395Z","steps":["trace[276904658] 'agreement among raft nodes before linearized reading' (duration: 453.614307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:47.960442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:47.505911Z","time spent":"454.525307ms","remote":"127.0.0.1:39648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-08-13T07:57:47.959511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:47.5008Z","time spent":"458.668277ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T07:57:47.960625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.609378ms","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-08-13T07:57:47.960753Z","caller":"traceutil/trace.go:171","msg":"trace[872312166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2092; }","duration":"257.782194ms","start":"2026-08-13T07:57:47.702918Z","end":"2026-08-13T07:57:47.9607Z","steps":["trace[872312166] 'agreement among raft nodes before linearized reading' (duration: 257.128452ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:47.960791Z","caller":"traceutil/trace.go:171","msg":"trace[646065337] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"461.490842ms","start":"2026-08-13T07:57:47.499246Z","end":"2026-08-13T07:57:47.960737Z","steps":["trace[646065337] 'process raft request' (duration: 183.020399ms)","trace[646065337] 'compare' (duration: 276.167866ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T07:57:47.960917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:47.499181Z","time spent":"461.672699ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T07:57:47.961326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.880857ms","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-08-13T07:57:47.961387Z","caller":"traceutil/trace.go:171","msg":"trace[1440025181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2092; }","duration":"238.9582ms","start":"2026-08-13T07:57:47.722399Z","end":"2026-08-13T07:57:47.961357Z","steps":["trace[1440025181] 'agreement among raft nodes before linearized reading' (duration: 238.639929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:48.681467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.981991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T07:57:48.681671Z","caller":"traceutil/trace.go:171","msg":"trace[688807414] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"615.541872ms","start":"2026-08-13T07:57:48.066114Z","end":"2026-08-13T07:57:48.681656Z","steps":["trace[688807414] 'process raft request' (duration: 615.506931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:48.681725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:48.066085Z","time spent":"615.614123ms","remote":"127.0.0.1:39760","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-08-13T07:57:48.681732Z","caller":"traceutil/trace.go:171","msg":"trace[1155752053] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"711.701121ms","start":"2026-08-13T07:57:47.970001Z","end":"2026-08-13T07:57:48.681702Z","steps":["trace[1155752053] 'process raft request' (duration: 342.403409ms)","trace[1155752053] 'compare' (duration: 368.557388ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T07:57:48.681808Z","caller":"traceutil/trace.go:171","msg":"trace[139738985] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2171; }","duration":"711.147772ms","start":"2026-08-13T07:57:47.970642Z","end":"2026-08-13T07:57:48.68179Z","steps":["trace[139738985] 'read index received' (duration: 341.787489ms)","trace[139738985] 'applied index is now lower than readState.Index' (duration: 369.358263ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T07:57:48.681896Z","caller":"traceutil/trace.go:171","msg":"trace[534897109] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"710.7976ms","start":"2026-08-13T07:57:47.97109Z","end":"2026-08-13T07:57:48.681888Z","steps":["trace[534897109] 'process raft request' (duration: 710.466149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:48.681944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:47.97108Z","time spent":"710.842063ms","remote":"127.0.0.1:39760","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-08-13T07:57:48.681953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:47.969972Z","time spent":"711.841215ms","remote":"127.0.0.1:39760","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-08-13T07:57:48.681957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.296797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-13T07:57:48.682006Z","caller":"traceutil/trace.go:171","msg":"trace[1162036606] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2095; }","duration":"711.37365ms","start":"2026-08-13T07:57:47.970622Z","end":"2026-08-13T07:57:48.681996Z","steps":["trace[1162036606] 'agreement among raft nodes before linearized reading' (duration: 711.256886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:48.682035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:47.970611Z","time spent":"711.417782ms","remote":"127.0.0.1:39772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-08-13T07:57:48.771082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.5596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T07:57:48.771196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.758166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T07:57:48.771113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.210947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-13T07:57:48.771234Z","caller":"traceutil/trace.go:171","msg":"trace[555533784] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2096; }","duration":"317.407836ms","start":"2026-08-13T07:57:48.453817Z","end":"2026-08-13T07:57:48.771225Z","steps":["trace[555533784] 'agreement among raft nodes before linearized reading' (duration: 317.151817ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:57:48.771239Z","caller":"traceutil/trace.go:171","msg":"trace[173894582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2096; }","duration":"186.876122ms","start":"2026-08-13T07:57:48.584347Z","end":"2026-08-13T07:57:48.771223Z","steps":["trace[173894582] 'agreement among raft nodes before linearized reading' (duration: 186.82712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:48.77127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:48.453788Z","time spent":"317.473467ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-13T07:57:48.771383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.466607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T07:57:48.771409Z","caller":"traceutil/trace.go:171","msg":"trace[808550208] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2096; }","duration":"710.491947ms","start":"2026-08-13T07:57:48.06091Z","end":"2026-08-13T07:57:48.771402Z","steps":["trace[808550208] 'agreement among raft nodes before linearized reading' (duration: 710.454136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:48.771428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:48.060873Z","time spent":"710.54999ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-13T07:57:48.771499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.940798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T07:57:48.771528Z","caller":"traceutil/trace.go:171","msg":"trace[654017881] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2096; }","duration":"153.03112ms","start":"2026-08-13T07:57:48.61849Z","end":"2026-08-13T07:57:48.771521Z","steps":["trace[654017881] 'agreement among raft nodes before linearized reading' (duration: 152.966858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:48.771128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.01001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T07:57:48.771198Z","caller":"traceutil/trace.go:171","msg":"trace[28802965] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2096; }","duration":"765.739046ms","start":"2026-08-13T07:57:48.005443Z","end":"2026-08-13T07:57:48.771182Z","steps":["trace[28802965] 'agreement among raft nodes before linearized reading' (duration: 765.5531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:48.771676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:48.005415Z","time spent":"766.232703ms","remote":"127.0.0.1:51526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-08-13T07:57:48.771582Z","caller":"traceutil/trace.go:171","msg":"trace[478173735] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2096; }","duration":"587.529828ms","start":"2026-08-13T07:57:48.184028Z","end":"2026-08-13T07:57:48.771558Z","steps":["trace[478173735] 'agreement among raft nodes before linearized reading' (duration: 586.99838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:48.771896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:48.183994Z","time spent":"587.87571ms","remote":"127.0.0.1:39934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-13T07:57:48.771614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.328342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-13T07:57:48.772284Z","caller":"traceutil/trace.go:171","msg":"trace[777692506] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2096; }","duration":"269.045766ms","start":"2026-08-13T07:57:48.503217Z","end":"2026-08-13T07:57:48.772263Z","steps":["trace[777692506] 'agreement among raft nodes before linearized reading' (duration: 268.26131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:57:49.317517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.042837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T07:57:49.317623Z","caller":"traceutil/trace.go:171","msg":"trace[617230743] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"319.552624ms","start":"2026-08-13T07:57:48.998056Z","end":"2026-08-13T07:57:49.317609Z","steps":["trace[617230743] 'process raft request' (duration: 174.312461ms)","trace[617230743] 'compare' (duration: 144.814179ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T07:57:49.317677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:57:48.99802Z","time spent":"319.633817ms","remote":"127.0.0.1:39760","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-08-13T07:58:52.545262Z","caller":"traceutil/trace.go:171","msg":"trace[173186335] linearizableReadLoop","detail":"{readStateIndex:2633; appliedIndex:2632; }","duration":"263.395452ms","start":"2026-08-13T07:58:52.281846Z","end":"2026-08-13T07:58:52.545242Z","steps":["trace[173186335] 'read index received' (duration: 263.248488ms)","trace[173186335] 'applied index is now lower than readState.Index' (duration: 146.084µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T07:58:52.545509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.347488ms","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-08-13T07:58:52.545491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.620318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T07:58:52.545548Z","caller":"traceutil/trace.go:171","msg":"trace[6234138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2540; }","duration":"252.42362ms","start":"2026-08-13T07:58:52.293114Z","end":"2026-08-13T07:58:52.545537Z","steps":["trace[6234138] 'agreement among raft nodes before linearized reading' (duration: 252.248954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:58:52.545548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.36745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-13T07:58:52.545571Z","caller":"traceutil/trace.go:171","msg":"trace[727082358] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2540; }","duration":"263.748832ms","start":"2026-08-13T07:58:52.281803Z","end":"2026-08-13T07:58:52.545552Z","steps":["trace[727082358] 'agreement among raft nodes before linearized reading' (duration: 263.558076ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:58:52.545588Z","caller":"traceutil/trace.go:171","msg":"trace[1992501766] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2540; }","duration":"251.42066ms","start":"2026-08-13T07:58:52.294155Z","end":"2026-08-13T07:58:52.545576Z","steps":["trace[1992501766] 'agreement among raft nodes before linearized reading' (duration: 251.279117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:58:52.545725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.877571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-13T07:58:52.545745Z","caller":"traceutil/trace.go:171","msg":"trace[1006339402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2540; }","duration":"227.926764ms","start":"2026-08-13T07:58:52.317812Z","end":"2026-08-13T07:58:52.545739Z","steps":["trace[1006339402] 'agreement among raft nodes before linearized reading' (duration: 227.567143ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:58:52.545922Z","caller":"traceutil/trace.go:171","msg":"trace[1049551769] transaction","detail":"{read_only:false; response_revision:2540; number_of_response:1; }","duration":"422.326902ms","start":"2026-08-13T07:58:52.12355Z","end":"2026-08-13T07:58:52.545876Z","steps":["trace[1049551769] 'process raft request' (duration: 421.573101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:58:52.546149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:58:52.123529Z","time spent":"422.515438ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T07:59:24.278892Z","caller":"traceutil/trace.go:171","msg":"trace[838677598] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"102.450152ms","start":"2026-08-13T07:59:24.176421Z","end":"2026-08-13T07:59:24.278871Z","steps":["trace[838677598] 'process raft request' (duration: 102.321558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:59:24.888436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.409334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T07:59:24.888519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.646416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-13T07:59:24.888532Z","caller":"traceutil/trace.go:171","msg":"trace[595440171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2717; }","duration":"302.552758ms","start":"2026-08-13T07:59:24.585964Z","end":"2026-08-13T07:59:24.888517Z","steps":["trace[595440171] 'range keys from in-memory index tree' (duration: 302.315162ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T07:59:24.888554Z","caller":"traceutil/trace.go:171","msg":"trace[1526556383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2717; }","duration":"132.705389ms","start":"2026-08-13T07:59:24.755836Z","end":"2026-08-13T07:59:24.888541Z","steps":["trace[1526556383] 'range keys from in-memory index tree' (duration: 132.545894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:59:24.888818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.570658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-13T07:59:24.888872Z","caller":"traceutil/trace.go:171","msg":"trace[458218800] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2717; }","duration":"107.656651ms","start":"2026-08-13T07:59:24.781203Z","end":"2026-08-13T07:59:24.88886Z","steps":["trace[458218800] 'range keys from in-memory index tree' (duration: 107.464165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T07:59:24.888561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T07:59:24.585942Z","time spent":"302.61208ms","remote":"127.0.0.1:39490","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-08-13T07:59:24.888436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.153091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T07:59:24.88919Z","caller":"traceutil/trace.go:171","msg":"trace[1179729392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2717; }","duration":"139.956153ms","start":"2026-08-13T07:59:24.749224Z","end":"2026-08-13T07:59:24.88918Z","steps":["trace[1179729392] 'range keys from in-memory index tree' (duration: 138.998807ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:00:03.499201Z","caller":"traceutil/trace.go:171","msg":"trace[465294542] linearizableReadLoop","detail":"{readStateIndex:3026; appliedIndex:3025; }","duration":"427.554609ms","start":"2026-08-13T08:00:03.071618Z","end":"2026-08-13T08:00:03.499173Z","steps":["trace[465294542] 'read index received' (duration: 423.549069ms)","trace[465294542] 'applied index is now lower than readState.Index' (duration: 4.00228ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:00:03.499338Z","caller":"traceutil/trace.go:171","msg":"trace[602281967] transaction","detail":"{read_only:false; response_revision:2918; number_of_response:1; }","duration":"343.122726ms","start":"2026-08-13T08:00:03.156181Z","end":"2026-08-13T08:00:03.499304Z","steps":["trace[602281967] 'process raft request' (duration: 342.891551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:00:03.499419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.784105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-08-13T08:00:03.499455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.062481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-13T08:00:03.499462Z","caller":"traceutil/trace.go:171","msg":"trace[281227678] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2919; }","duration":"427.862687ms","start":"2026-08-13T08:00:03.071586Z","end":"2026-08-13T08:00:03.499449Z","steps":["trace[281227678] 'agreement among raft nodes before linearized reading' (duration: 427.732314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:00:03.499491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:00:03.156152Z","time spent":"343.257719ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:00:03.49952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:00:03.071571Z","time spent":"427.937349ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":550,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-13T08:00:03.499589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.874142ms","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-08-13T08:00:03.499504Z","caller":"traceutil/trace.go:171","msg":"trace[439568732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2919; }","duration":"394.167963ms","start":"2026-08-13T08:00:03.105325Z","end":"2026-08-13T08:00:03.499493Z","steps":["trace[439568732] 'agreement among raft nodes before linearized reading' (duration: 393.99957ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:00:03.499632Z","caller":"traceutil/trace.go:171","msg":"trace[1845283658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2919; }","duration":"217.960013ms","start":"2026-08-13T08:00:03.281663Z","end":"2026-08-13T08:00:03.499623Z","steps":["trace[1845283658] 'agreement among raft nodes before linearized reading' (duration: 217.867502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:00:03.499634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:00:03.105301Z","time spent":"394.323887ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T08:00:03.499673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.009114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-13T08:00:03.4997Z","caller":"traceutil/trace.go:171","msg":"trace[2039431654] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2919; }","duration":"389.068437ms","start":"2026-08-13T08:00:03.110622Z","end":"2026-08-13T08:00:03.49969Z","steps":["trace[2039431654] 'agreement among raft nodes before linearized reading' (duration: 389.006505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:00:03.499711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.886952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T08:00:03.499739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.651966ms","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-08-13T08:00:03.499747Z","caller":"traceutil/trace.go:171","msg":"trace[1266822501] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2919; }","duration":"167.923422ms","start":"2026-08-13T08:00:03.331814Z","end":"2026-08-13T08:00:03.499737Z","steps":["trace[1266822501] 'agreement among raft nodes before linearized reading' (duration: 167.870901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:00:03.499772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.222436ms","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-08-13T08:00:03.499796Z","caller":"traceutil/trace.go:171","msg":"trace[417556742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2919; }","duration":"390.284246ms","start":"2026-08-13T08:00:03.109505Z","end":"2026-08-13T08:00:03.499789Z","steps":["trace[417556742] 'agreement among raft nodes before linearized reading' (duration: 390.226854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:00:03.499815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:00:03.109484Z","time spent":"390.326247ms","remote":"127.0.0.1:39760","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":"warn","ts":"2026-08-13T08:00:03.49972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:00:03.110604Z","time spent":"389.109207ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-13T08:00:03.49985Z","caller":"traceutil/trace.go:171","msg":"trace[974619057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2919; }","duration":"189.703938ms","start":"2026-08-13T08:00:03.31005Z","end":"2026-08-13T08:00:03.499754Z","steps":["trace[974619057] 'agreement among raft nodes before linearized reading' (duration: 189.641146ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:00:03.499256Z","caller":"traceutil/trace.go:171","msg":"trace[1727623445] transaction","detail":"{read_only:false; response_revision:2919; number_of_response:1; }","duration":"266.599562ms","start":"2026-08-13T08:00:03.232638Z","end":"2026-08-13T08:00:03.499238Z","steps":["trace[1727623445] 'process raft request' (duration: 266.555301ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:07.967404Z","caller":"traceutil/trace.go:171","msg":"trace[358355572] transaction","detail":"{read_only:false; response_revision:4250; number_of_response:1; }","duration":"102.150458ms","start":"2026-08-13T08:03:07.865234Z","end":"2026-08-13T08:03:07.967384Z","steps":["trace[358355572] 'process raft request' (duration: 70.541448ms)","trace[358355572] 'compare' (duration: 31.443117ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:03:07.967909Z","caller":"traceutil/trace.go:171","msg":"trace[920200384] transaction","detail":"{read_only:false; response_revision:4253; number_of_response:1; }","duration":"100.087635ms","start":"2026-08-13T08:03:07.867786Z","end":"2026-08-13T08:03:07.967874Z","steps":["trace[920200384] 'process raft request' (duration: 99.90982ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:07.96819Z","caller":"traceutil/trace.go:171","msg":"trace[1973516813] transaction","detail":"{read_only:false; response_revision:4252; number_of_response:1; }","duration":"100.453221ms","start":"2026-08-13T08:03:07.867719Z","end":"2026-08-13T08:03:07.968172Z","steps":["trace[1973516813] 'process raft request' (duration: 99.727966ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:07.968489Z","caller":"traceutil/trace.go:171","msg":"trace[445545607] transaction","detail":"{read_only:false; response_revision:4251; number_of_response:1; }","duration":"102.223949ms","start":"2026-08-13T08:03:07.865462Z","end":"2026-08-13T08:03:07.967686Z","steps":["trace[445545607] 'process raft request' (duration: 101.844141ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:07.968591Z","caller":"traceutil/trace.go:171","msg":"trace[1272590204] transaction","detail":"{read_only:false; response_revision:4254; number_of_response:1; }","duration":"100.749518ms","start":"2026-08-13T08:03:07.867819Z","end":"2026-08-13T08:03:07.968569Z","steps":["trace[1272590204] 'process raft request' (duration: 99.92655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:07.96941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.279128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/monitoring/kube-prometheus-stack-operator-cd88cf4bf\" ","response":"range_response_count:1 size:5116"} {"level":"warn","ts":"2026-08-13T08:03:07.969457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.244388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9\" ","response":"range_response_count:1 size:4805"} {"level":"info","ts":"2026-08-13T08:03:07.969462Z","caller":"traceutil/trace.go:171","msg":"trace[1694878388] range","detail":"{range_begin:/registry/replicasets/monitoring/kube-prometheus-stack-operator-cd88cf4bf; range_end:; response_count:1; response_revision:4263; }","duration":"100.34766ms","start":"2026-08-13T08:03:07.8691Z","end":"2026-08-13T08:03:07.969448Z","steps":["trace[1694878388] 'agreement among raft nodes before linearized reading' (duration: 100.219337ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:07.969516Z","caller":"traceutil/trace.go:171","msg":"trace[1858017311] range","detail":"{range_begin:/registry/replicasets/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9; range_end:; response_count:1; response_revision:4263; }","duration":"100.304559ms","start":"2026-08-13T08:03:07.869176Z","end":"2026-08-13T08:03:07.96948Z","steps":["trace[1858017311] 'agreement among raft nodes before linearized reading' (duration: 100.126445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:07.969749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.980365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-kube-state-metrics\" ","response":"range_response_count:1 size:5066"} {"level":"info","ts":"2026-08-13T08:03:07.969827Z","caller":"traceutil/trace.go:171","msg":"trace[1677893845] range","detail":"{range_begin:/registry/deployments/monitoring/kube-prometheus-stack-kube-state-metrics; range_end:; response_count:1; response_revision:4263; }","duration":"102.077357ms","start":"2026-08-13T08:03:07.867733Z","end":"2026-08-13T08:03:07.96981Z","steps":["trace[1677893845] 'agreement among raft nodes before linearized reading' (duration: 101.839131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:07.969951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.420778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/monitoring/kube-prometheus-stack-prometheus-node-exporter\" ","response":"range_response_count:1 size:8235"} {"level":"info","ts":"2026-08-13T08:03:07.969984Z","caller":"traceutil/trace.go:171","msg":"trace[1032042731] range","detail":"{range_begin:/registry/daemonsets/monitoring/kube-prometheus-stack-prometheus-node-exporter; range_end:; response_count:1; response_revision:4263; }","duration":"104.472348ms","start":"2026-08-13T08:03:07.865503Z","end":"2026-08-13T08:03:07.969976Z","steps":["trace[1032042731] 'agreement among raft nodes before linearized reading' (duration: 104.306555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:12.679032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.58717ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-13T08:03:12.679303Z","caller":"traceutil/trace.go:171","msg":"trace[895775519] linearizableReadLoop","detail":"{readStateIndex:4629; appliedIndex:4628; }","duration":"133.406934ms","start":"2026-08-13T08:03:12.545857Z","end":"2026-08-13T08:03:12.679264Z","steps":["trace[895775519] 'read index received' (duration: 12.499038ms)","trace[895775519] 'applied index is now lower than readState.Index' (duration: 120.902826ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:03:12.679642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.708583ms","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-08-13T08:03:12.679621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.740042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T08:03:12.679679Z","caller":"traceutil/trace.go:171","msg":"trace[1499728485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4451; }","duration":"133.806633ms","start":"2026-08-13T08:03:12.545863Z","end":"2026-08-13T08:03:12.67967Z","steps":["trace[1499728485] 'agreement among raft nodes before linearized reading' (duration: 133.602139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:12.679695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.089443ms","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-08-13T08:03:12.679771Z","caller":"traceutil/trace.go:171","msg":"trace[1092221942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4451; }","duration":"123.187385ms","start":"2026-08-13T08:03:12.556567Z","end":"2026-08-13T08:03:12.679754Z","steps":["trace[1092221942] 'agreement among raft nodes before linearized reading' (duration: 122.974021ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:12.679793Z","caller":"traceutil/trace.go:171","msg":"trace[1045420127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4451; }","duration":"133.833634ms","start":"2026-08-13T08:03:12.545852Z","end":"2026-08-13T08:03:12.679685Z","steps":["trace[1045420127] 'agreement among raft nodes before linearized reading' (duration: 133.568158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:12.679805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.506439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-13T08:03:12.679885Z","caller":"traceutil/trace.go:171","msg":"trace[47884666] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4451; }","duration":"127.625591ms","start":"2026-08-13T08:03:12.552238Z","end":"2026-08-13T08:03:12.679864Z","steps":["trace[47884666] 'agreement among raft nodes before linearized reading' (duration: 127.368716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:15.569194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.214013ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:03:15.569366Z","caller":"traceutil/trace.go:171","msg":"trace[1408496914] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4510; }","duration":"198.403796ms","start":"2026-08-13T08:03:15.370937Z","end":"2026-08-13T08:03:15.569341Z","steps":["trace[1408496914] 'range keys from in-memory index tree' (duration: 198.196503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:15.570409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.306781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:03:15.572131Z","caller":"traceutil/trace.go:171","msg":"trace[864860949] transaction","detail":"{read_only:false; response_revision:4511; number_of_response:1; }","duration":"393.858829ms","start":"2026-08-13T08:03:15.178233Z","end":"2026-08-13T08:03:15.572092Z","steps":["trace[864860949] 'process raft request' (duration: 154.77029ms)","trace[864860949] 'compare' (duration: 235.663556ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:03:15.572491Z","caller":"traceutil/trace.go:171","msg":"trace[225971164] linearizableReadLoop","detail":"{readStateIndex:4690; appliedIndex:4688; }","duration":"391.690143ms","start":"2026-08-13T08:03:15.180778Z","end":"2026-08-13T08:03:15.572469Z","steps":["trace[225971164] 'read index received' (duration: 48.887908ms)","trace[225971164] 'applied index is now lower than readState.Index' (duration: 342.800895ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:03:15.572884Z","caller":"traceutil/trace.go:171","msg":"trace[458592952] transaction","detail":"{read_only:false; response_revision:4512; number_of_response:1; }","duration":"223.333341ms","start":"2026-08-13T08:03:15.349503Z","end":"2026-08-13T08:03:15.572836Z","steps":["trace[458592952] 'process raft request' (duration: 222.217798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:15.573217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.43057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-13T08:03:15.573288Z","caller":"traceutil/trace.go:171","msg":"trace[1695767181] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4512; }","duration":"392.520902ms","start":"2026-08-13T08:03:15.180738Z","end":"2026-08-13T08:03:15.573259Z","steps":["trace[1695767181] 'agreement among raft nodes before linearized reading' (duration: 391.822876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:15.573334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:15.180725Z","time spent":"392.600122ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1365,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-08-13T08:03:15.572336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:15.178208Z","time spent":"394.018213ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:03:15.578259Z","caller":"traceutil/trace.go:171","msg":"trace[232192690] transaction","detail":"{read_only:false; response_revision:4513; number_of_response:1; }","duration":"151.49489ms","start":"2026-08-13T08:03:15.426743Z","end":"2026-08-13T08:03:15.578238Z","steps":["trace[232192690] 'process raft request' (duration: 151.183663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:15.579389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.678665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:1954231"} {"level":"info","ts":"2026-08-13T08:03:15.579526Z","caller":"traceutil/trace.go:171","msg":"trace[1938856812] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4513; }","duration":"227.886329ms","start":"2026-08-13T08:03:15.351627Z","end":"2026-08-13T08:03:15.579513Z","steps":["trace[1938856812] 'agreement among raft nodes before linearized reading' (duration: 227.02547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:15.579857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.51741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:11916"} {"level":"warn","ts":"2026-08-13T08:03:15.580138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.877907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-tg5qf\" ","response":"range_response_count:1 size:5041"} {"level":"info","ts":"2026-08-13T08:03:15.580219Z","caller":"traceutil/trace.go:171","msg":"trace[361465768] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-tg5qf; range_end:; response_count:1; response_revision:4513; }","duration":"103.003319ms","start":"2026-08-13T08:03:15.477195Z","end":"2026-08-13T08:03:15.580198Z","steps":["trace[361465768] 'agreement among raft nodes before linearized reading' (duration: 102.845776ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:15.579901Z","caller":"traceutil/trace.go:171","msg":"trace[219435684] range","detail":"{range_begin:/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4513; }","duration":"336.60012ms","start":"2026-08-13T08:03:15.243291Z","end":"2026-08-13T08:03:15.579891Z","steps":["trace[219435684] 'agreement among raft nodes before linearized reading' (duration: 336.495558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:15.581215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:15.243268Z","time spent":"337.93478ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":11938,"request content":"key:\"/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-08-13T08:03:15.581235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.82592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:5 size:42166"} {"level":"info","ts":"2026-08-13T08:03:15.581564Z","caller":"traceutil/trace.go:171","msg":"trace[1484447692] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:5; response_revision:4513; }","duration":"340.26025ms","start":"2026-08-13T08:03:15.241286Z","end":"2026-08-13T08:03:15.581546Z","steps":["trace[1484447692] 'agreement among raft nodes before linearized reading' (duration: 337.138283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:15.581629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:15.241258Z","time spent":"340.358291ms","remote":"127.0.0.1:39666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":42188,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"warn","ts":"2026-08-13T08:03:17.096836Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515236601572894373,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-13T08:03:17.256171Z","caller":"traceutil/trace.go:171","msg":"trace[1164836876] transaction","detail":"{read_only:false; response_revision:4559; number_of_response:1; }","duration":"660.89164ms","start":"2026-08-13T08:03:16.59526Z","end":"2026-08-13T08:03:17.256152Z","steps":["trace[1164836876] 'process raft request' (duration: 648.832281ms)","trace[1164836876] 'compare' (duration: 11.572319ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:03:17.256281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:16.595236Z","time spent":"660.985964ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:03:17.265742Z","caller":"traceutil/trace.go:171","msg":"trace[1729943638] linearizableReadLoop","detail":"{readStateIndex:4739; appliedIndex:4737; }","duration":"669.072056ms","start":"2026-08-13T08:03:16.59665Z","end":"2026-08-13T08:03:17.265722Z","steps":["trace[1729943638] 'read index received' (duration: 647.451714ms)","trace[1729943638] 'applied index is now lower than readState.Index' (duration: 21.619422ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:03:17.26623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.547887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:15300"} {"level":"warn","ts":"2026-08-13T08:03:17.266345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.994667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:03:17.266416Z","caller":"traceutil/trace.go:171","msg":"trace[403535724] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:4560; }","duration":"144.054507ms","start":"2026-08-13T08:03:17.122314Z","end":"2026-08-13T08:03:17.266368Z","steps":["trace[403535724] 'agreement among raft nodes before linearized reading' (duration: 143.979676ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:17.266419Z","caller":"traceutil/trace.go:171","msg":"trace[965342680] range","detail":"{range_begin:/registry/statefulsets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4560; }","duration":"669.66992ms","start":"2026-08-13T08:03:16.596627Z","end":"2026-08-13T08:03:17.266297Z","steps":["trace[965342680] 'agreement among raft nodes before linearized reading' (duration: 669.420974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:17.266523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.429218ms","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-08-13T08:03:17.266538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.522432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:03:17.266575Z","caller":"traceutil/trace.go:171","msg":"trace[1284665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4560; }","duration":"526.49854ms","start":"2026-08-13T08:03:16.740067Z","end":"2026-08-13T08:03:17.266565Z","steps":["trace[1284665] 'agreement among raft nodes before linearized reading' (duration: 526.345387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:17.266625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:16.740019Z","time spent":"526.594681ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-13T08:03:17.266659Z","caller":"traceutil/trace.go:171","msg":"trace[1062000477] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4560; }","duration":"146.641413ms","start":"2026-08-13T08:03:17.119963Z","end":"2026-08-13T08:03:17.266605Z","steps":["trace[1062000477] 'agreement among raft nodes before linearized reading' (duration: 146.528061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:17.266682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.970319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T08:03:17.267342Z","caller":"traceutil/trace.go:171","msg":"trace[1304989056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4560; }","duration":"527.674944ms","start":"2026-08-13T08:03:16.739659Z","end":"2026-08-13T08:03:17.267334Z","steps":["trace[1304989056] 'agreement among raft nodes before linearized reading' (duration: 526.932888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:17.267365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:16.739646Z","time spent":"527.711435ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T08:03:17.266663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.456837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c867cc9d-fc51-4917-ad29-1bdfb6106181\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-08-13T08:03:17.267446Z","caller":"traceutil/trace.go:171","msg":"trace[870173090] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c867cc9d-fc51-4917-ad29-1bdfb6106181; range_end:; response_count:1; response_revision:4560; }","duration":"228.304814ms","start":"2026-08-13T08:03:17.039132Z","end":"2026-08-13T08:03:17.267437Z","steps":["trace[870173090] 'agreement among raft nodes before linearized reading' (duration: 227.400255ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:17.26653Z","caller":"traceutil/trace.go:171","msg":"trace[575829433] transaction","detail":"{read_only:false; response_revision:4560; number_of_response:1; }","duration":"543.652727ms","start":"2026-08-13T08:03:16.722859Z","end":"2026-08-13T08:03:17.266511Z","steps":["trace[575829433] 'process raft request' (duration: 542.753937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:17.267614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:16.722835Z","time spent":"544.71866ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:03:17.266264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.494511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4b2cc4bf-cd2c-4bd4-b3f7-922537a1dd04\" ","response":"range_response_count:1 size:3893"} {"level":"info","ts":"2026-08-13T08:03:17.267804Z","caller":"traceutil/trace.go:171","msg":"trace[66472783] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4b2cc4bf-cd2c-4bd4-b3f7-922537a1dd04; range_end:; response_count:1; response_revision:4560; }","duration":"564.077545ms","start":"2026-08-13T08:03:16.703713Z","end":"2026-08-13T08:03:17.26779Z","steps":["trace[66472783] 'agreement among raft nodes before linearized reading' (duration: 562.43978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:17.267841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:16.703656Z","time spent":"564.173667ms","remote":"127.0.0.1:39666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3915,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4b2cc4bf-cd2c-4bd4-b3f7-922537a1dd04\" "} {"level":"warn","ts":"2026-08-13T08:03:17.266755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.596207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-13T08:03:17.267983Z","caller":"traceutil/trace.go:171","msg":"trace[1067926027] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4560; }","duration":"538.839354ms","start":"2026-08-13T08:03:16.729132Z","end":"2026-08-13T08:03:17.267972Z","steps":["trace[1067926027] 'agreement among raft nodes before linearized reading' (duration: 537.573506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:17.268016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:16.729102Z","time spent":"538.903025ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-13T08:03:17.266736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:16.596619Z","time spent":"669.941676ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":15322,"request content":"key:\"/registry/statefulsets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-08-13T08:03:17.266756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.006271ms","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-08-13T08:03:17.268446Z","caller":"traceutil/trace.go:171","msg":"trace[587485867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4560; }","duration":"528.711506ms","start":"2026-08-13T08:03:16.739723Z","end":"2026-08-13T08:03:17.268434Z","steps":["trace[587485867] 'agreement among raft nodes before linearized reading' (duration: 526.880777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:17.26851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:16.739717Z","time spent":"528.781778ms","remote":"127.0.0.1:39760","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-08-13T08:03:17.512116Z","caller":"traceutil/trace.go:171","msg":"trace[709994209] transaction","detail":"{read_only:false; response_revision:4563; number_of_response:1; }","duration":"237.91354ms","start":"2026-08-13T08:03:17.274186Z","end":"2026-08-13T08:03:17.512099Z","steps":["trace[709994209] 'process raft request' (duration: 236.936209ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:17.512466Z","caller":"traceutil/trace.go:171","msg":"trace[843466732] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"238.211926ms","start":"2026-08-13T08:03:17.274224Z","end":"2026-08-13T08:03:17.512436Z","steps":["trace[843466732] 'process raft request' (duration: 237.845649ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:17.512567Z","caller":"traceutil/trace.go:171","msg":"trace[306188512] linearizableReadLoop","detail":"{readStateIndex:4745; appliedIndex:4741; }","duration":"189.000002ms","start":"2026-08-13T08:03:17.323557Z","end":"2026-08-13T08:03:17.512557Z","steps":["trace[306188512] 'read index received' (duration: 187.583092ms)","trace[306188512] 'applied index is now lower than readState.Index' (duration: 1.41635ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:03:17.512832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.243837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:03:17.51294Z","caller":"traceutil/trace.go:171","msg":"trace[8993215] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:4565; }","duration":"189.37572ms","start":"2026-08-13T08:03:17.323539Z","end":"2026-08-13T08:03:17.512914Z","steps":["trace[8993215] 'agreement among raft nodes before linearized reading' (duration: 189.089114ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:17.51311Z","caller":"traceutil/trace.go:171","msg":"trace[1439901404] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"226.635558ms","start":"2026-08-13T08:03:17.286464Z","end":"2026-08-13T08:03:17.513099Z","steps":["trace[1439901404] 'process raft request' (duration: 226.042136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:19.512928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.069072ms","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-08-13T08:03:19.51304Z","caller":"traceutil/trace.go:171","msg":"trace[403887963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4617; }","duration":"253.216816ms","start":"2026-08-13T08:03:19.2598Z","end":"2026-08-13T08:03:19.513017Z","steps":["trace[403887963] 'range keys from in-memory index tree' (duration: 252.894118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:19.513552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.247192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:03:19.513603Z","caller":"traceutil/trace.go:171","msg":"trace[483405125] linearizableReadLoop","detail":"{readStateIndex:4800; appliedIndex:4799; }","duration":"233.448532ms","start":"2026-08-13T08:03:19.280145Z","end":"2026-08-13T08:03:19.513594Z","steps":["trace[483405125] 'read index received' (duration: 102.028197ms)","trace[483405125] 'applied index is now lower than readState.Index' (duration: 131.419375ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:03:19.513812Z","caller":"traceutil/trace.go:171","msg":"trace[1102200173] transaction","detail":"{read_only:false; response_revision:4618; number_of_response:1; }","duration":"240.225557ms","start":"2026-08-13T08:03:19.273576Z","end":"2026-08-13T08:03:19.513801Z","steps":["trace[1102200173] 'process raft request' (duration: 108.681989ms)","trace[1102200173] 'compare' (duration: 131.16577ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:03:19.513963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.818029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4b2cc4bf-cd2c-4bd4-b3f7-922537a1dd04\" ","response":"range_response_count:1 size:4079"} {"level":"info","ts":"2026-08-13T08:03:19.513997Z","caller":"traceutil/trace.go:171","msg":"trace[296838483] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4b2cc4bf-cd2c-4bd4-b3f7-922537a1dd04; range_end:; response_count:1; response_revision:4618; }","duration":"233.88768ms","start":"2026-08-13T08:03:19.2801Z","end":"2026-08-13T08:03:19.513988Z","steps":["trace[296838483] 'agreement among raft nodes before linearized reading' (duration: 233.811739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:19.514162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.328388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c867cc9d-fc51-4917-ad29-1bdfb6106181\" ","response":"range_response_count:1 size:4052"} {"level":"info","ts":"2026-08-13T08:03:19.514189Z","caller":"traceutil/trace.go:171","msg":"trace[1142397174] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c867cc9d-fc51-4917-ad29-1bdfb6106181; range_end:; response_count:1; response_revision:4618; }","duration":"233.38488ms","start":"2026-08-13T08:03:19.280797Z","end":"2026-08-13T08:03:19.514182Z","steps":["trace[1142397174] 'agreement among raft nodes before linearized reading' (duration: 233.314019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:19.514436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.627039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:03:19.514484Z","caller":"traceutil/trace.go:171","msg":"trace[829335688] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:4618; }","duration":"116.7187ms","start":"2026-08-13T08:03:19.397757Z","end":"2026-08-13T08:03:19.514476Z","steps":["trace[829335688] 'agreement among raft nodes before linearized reading' (duration: 116.607498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:19.514634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.846733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T08:03:19.514661Z","caller":"traceutil/trace.go:171","msg":"trace[1951610276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4618; }","duration":"214.892695ms","start":"2026-08-13T08:03:19.299762Z","end":"2026-08-13T08:03:19.514655Z","steps":["trace[1951610276] 'agreement among raft nodes before linearized reading' (duration: 214.825383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:19.514778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.199622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-13T08:03:19.514806Z","caller":"traceutil/trace.go:171","msg":"trace[1357207137] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4618; }","duration":"216.226732ms","start":"2026-08-13T08:03:19.298571Z","end":"2026-08-13T08:03:19.514798Z","steps":["trace[1357207137] 'agreement among raft nodes before linearized reading' (duration: 216.165882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:26.099735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.023207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:03:26.099879Z","caller":"traceutil/trace.go:171","msg":"trace[1377680343] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"537.568651ms","start":"2026-08-13T08:03:25.562277Z","end":"2026-08-13T08:03:26.099846Z","steps":["trace[1377680343] 'process raft request' (duration: 31.307479ms)","trace[1377680343] 'compare' (duration: 505.745721ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:03:26.099961Z","caller":"traceutil/trace.go:171","msg":"trace[657072330] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"428.417198ms","start":"2026-08-13T08:03:25.671526Z","end":"2026-08-13T08:03:26.099944Z","steps":["trace[657072330] 'process raft request' (duration: 428.324506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:26.099986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:25.562261Z","time spent":"537.669814ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:03:26.100043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:25.671501Z","time spent":"428.497279ms","remote":"127.0.0.1:39760","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-08-13T08:03:26.10016Z","caller":"traceutil/trace.go:171","msg":"trace[2051624967] linearizableReadLoop","detail":"{readStateIndex:4936; appliedIndex:4935; }","duration":"515.572532ms","start":"2026-08-13T08:03:25.584575Z","end":"2026-08-13T08:03:26.100147Z","steps":["trace[2051624967] 'read index received' (duration: 8.977443ms)","trace[2051624967] 'applied index is now lower than readState.Index' (duration: 506.593469ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:03:26.100396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.812406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T08:03:26.10044Z","caller":"traceutil/trace.go:171","msg":"trace[137000874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4751; }","duration":"515.879007ms","start":"2026-08-13T08:03:25.584548Z","end":"2026-08-13T08:03:26.100427Z","steps":["trace[137000874] 'agreement among raft nodes before linearized reading' (duration: 515.742934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:26.100478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:25.584532Z","time spent":"515.935ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T08:03:26.100606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.00781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:03:26.100641Z","caller":"traceutil/trace.go:171","msg":"trace[2099822518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4751; }","duration":"516.067571ms","start":"2026-08-13T08:03:25.584562Z","end":"2026-08-13T08:03:26.100629Z","steps":["trace[2099822518] 'agreement among raft nodes before linearized reading' (duration: 516.015961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:26.100673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:25.584546Z","time spent":"516.118613ms","remote":"127.0.0.1:39490","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-08-13T08:03:26.100949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.599064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-13T08:03:26.101001Z","caller":"traceutil/trace.go:171","msg":"trace[2039076107] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4751; }","duration":"510.657476ms","start":"2026-08-13T08:03:25.590326Z","end":"2026-08-13T08:03:26.100983Z","steps":["trace[2039076107] 'agreement among raft nodes before linearized reading' (duration: 510.521482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:26.101041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:25.590322Z","time spent":"510.707946ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-13T08:03:26.101068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.963214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-13T08:03:26.101117Z","caller":"traceutil/trace.go:171","msg":"trace[1657382646] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4751; }","duration":"512.022976ms","start":"2026-08-13T08:03:25.589075Z","end":"2026-08-13T08:03:26.101098Z","steps":["trace[1657382646] 'agreement among raft nodes before linearized reading' (duration: 511.904924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:26.101158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:25.589062Z","time spent":"512.082667ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-08-13T08:03:26.101297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.863317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f115f7b3-4e9f-475e-ae8f-d79ef055fc95\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-13T08:03:26.101342Z","caller":"traceutil/trace.go:171","msg":"trace[530214940] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f115f7b3-4e9f-475e-ae8f-d79ef055fc95; range_end:; response_count:1; response_revision:4751; }","duration":"327.93424ms","start":"2026-08-13T08:03:25.773393Z","end":"2026-08-13T08:03:26.101328Z","steps":["trace[530214940] 'agreement among raft nodes before linearized reading' (duration: 327.812737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:26.101378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:25.77336Z","time spent":"328.008911ms","remote":"127.0.0.1:39666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f115f7b3-4e9f-475e-ae8f-d79ef055fc95\" "} {"level":"warn","ts":"2026-08-13T08:03:26.100953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.123826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-dddf64588-2dj5p.18cb4eb23a80f1e5\" ","response":"range_response_count:1 size:893"} {"level":"info","ts":"2026-08-13T08:03:26.101581Z","caller":"traceutil/trace.go:171","msg":"trace[976110723] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-dddf64588-2dj5p.18cb4eb23a80f1e5; range_end:; response_count:1; response_revision:4751; }","duration":"511.76476ms","start":"2026-08-13T08:03:25.589805Z","end":"2026-08-13T08:03:26.10157Z","steps":["trace[976110723] 'agreement among raft nodes before linearized reading' (duration: 511.056194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:26.101616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:25.589799Z","time spent":"511.80642ms","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":915,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-dddf64588-2dj5p.18cb4eb23a80f1e5\" "} {"level":"warn","ts":"2026-08-13T08:03:26.101765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.84216ms","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-08-13T08:03:26.101788Z","caller":"traceutil/trace.go:171","msg":"trace[628202030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4751; }","duration":"510.88089ms","start":"2026-08-13T08:03:25.590899Z","end":"2026-08-13T08:03:26.10178Z","steps":["trace[628202030] 'agreement among raft nodes before linearized reading' (duration: 510.812218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:26.101814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:25.590895Z","time spent":"510.911511ms","remote":"127.0.0.1:39760","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-08-13T08:03:26.101958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.953762ms","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-08-13T08:03:26.102069Z","caller":"traceutil/trace.go:171","msg":"trace[2037202145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4751; }","duration":"511.101255ms","start":"2026-08-13T08:03:25.590944Z","end":"2026-08-13T08:03:26.102045Z","steps":["trace[2037202145] 'agreement among raft nodes before linearized reading' (duration: 510.645205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:26.102781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:25.590936Z","time spent":"511.82891ms","remote":"127.0.0.1:39760","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-08-13T08:03:26.101532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.372316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-08-13T08:03:26.103418Z","caller":"traceutil/trace.go:171","msg":"trace[1212711696] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4751; }","duration":"516.240195ms","start":"2026-08-13T08:03:25.58713Z","end":"2026-08-13T08:03:26.10337Z","steps":["trace[1212711696] 'agreement among raft nodes before linearized reading' (duration: 514.291474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:26.103598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:25.587122Z","time spent":"516.420859ms","remote":"127.0.0.1:39666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16253,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-08-13T08:03:26.795697Z","caller":"traceutil/trace.go:171","msg":"trace[913962887] linearizableReadLoop","detail":"{readStateIndex:4953; appliedIndex:4952; }","duration":"192.7759ms","start":"2026-08-13T08:03:26.602903Z","end":"2026-08-13T08:03:26.795679Z","steps":["trace[913962887] 'read index received' (duration: 192.653627ms)","trace[913962887] 'applied index is now lower than readState.Index' (duration: 121.413µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:03:26.795736Z","caller":"traceutil/trace.go:171","msg":"trace[162980383] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"197.431139ms","start":"2026-08-13T08:03:26.598275Z","end":"2026-08-13T08:03:26.795706Z","steps":["trace[162980383] 'process raft request' (duration: 197.278536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:26.795998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.078386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12972"} {"level":"warn","ts":"2026-08-13T08:03:26.796034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.534976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-13T08:03:26.796092Z","caller":"traceutil/trace.go:171","msg":"trace[1640426459] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4767; }","duration":"128.64297ms","start":"2026-08-13T08:03:26.667431Z","end":"2026-08-13T08:03:26.796074Z","steps":["trace[1640426459] 'agreement among raft nodes before linearized reading' (duration: 128.497467ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:26.796076Z","caller":"traceutil/trace.go:171","msg":"trace[1085106407] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4767; }","duration":"193.197608ms","start":"2026-08-13T08:03:26.602855Z","end":"2026-08-13T08:03:26.796053Z","steps":["trace[1085106407] 'agreement among raft nodes before linearized reading' (duration: 192.940582ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:26.967815Z","caller":"traceutil/trace.go:171","msg":"trace[651793488] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"113.711971ms","start":"2026-08-13T08:03:26.854077Z","end":"2026-08-13T08:03:26.967789Z","steps":["trace[651793488] 'process raft request' (duration: 113.6612ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:26.968364Z","caller":"traceutil/trace.go:171","msg":"trace[788422275] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"114.330983ms","start":"2026-08-13T08:03:26.854016Z","end":"2026-08-13T08:03:26.968347Z","steps":["trace[788422275] 'process raft request' (duration: 113.673498ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:26.968752Z","caller":"traceutil/trace.go:171","msg":"trace[253835741] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"114.778983ms","start":"2026-08-13T08:03:26.853957Z","end":"2026-08-13T08:03:26.968736Z","steps":["trace[253835741] 'process raft request' (duration: 112.685909ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:03:39.025376Z","caller":"traceutil/trace.go:171","msg":"trace[1048424869] linearizableReadLoop","detail":"{readStateIndex:5116; appliedIndex:5115; }","duration":"303.4752ms","start":"2026-08-13T08:03:38.721879Z","end":"2026-08-13T08:03:39.025355Z","steps":["trace[1048424869] 'read index received' (duration: 303.277836ms)","trace[1048424869] 'applied index is now lower than readState.Index' (duration: 196.374µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:03:39.025533Z","caller":"traceutil/trace.go:171","msg":"trace[359866363] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"313.363181ms","start":"2026-08-13T08:03:38.71214Z","end":"2026-08-13T08:03:39.025503Z","steps":["trace[359866363] 'process raft request' (duration: 313.076225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:39.025596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.710256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-13T08:03:39.02564Z","caller":"traceutil/trace.go:171","msg":"trace[322975637] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4925; }","duration":"303.786126ms","start":"2026-08-13T08:03:38.721838Z","end":"2026-08-13T08:03:39.025624Z","steps":["trace[322975637] 'agreement among raft nodes before linearized reading' (duration: 303.633334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:03:39.025671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:38.721819Z","time spent":"303.844167ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-13T08:03:39.025685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:03:38.712117Z","time spent":"313.497573ms","remote":"127.0.0.1:39666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:03:39.026853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.322614ms","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-08-13T08:03:39.026976Z","caller":"traceutil/trace.go:171","msg":"trace[2050339656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4925; }","duration":"139.592261ms","start":"2026-08-13T08:03:38.887367Z","end":"2026-08-13T08:03:39.026959Z","steps":["trace[2050339656] 'agreement among raft nodes before linearized reading' (duration: 138.144891ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:04:07.848979Z","caller":"traceutil/trace.go:171","msg":"trace[1319911169] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"109.87059ms","start":"2026-08-13T08:04:07.739089Z","end":"2026-08-13T08:04:07.84896Z","steps":["trace[1319911169] 'process raft request' (duration: 109.746038ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:04:54.14613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2875} {"level":"info","ts":"2026-08-13T08:04:54.242852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2875,"took":"95.291923ms","hash":1963578754,"current-db-size-bytes":53854208,"current-db-size":"54 MB","current-db-size-in-use-bytes":29114368,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-13T08:04:54.242902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1963578754,"revision":2875,"compact-revision":-1} {"level":"info","ts":"2026-08-13T08:07:11.588097Z","caller":"traceutil/trace.go:171","msg":"trace[1272149465] transaction","detail":"{read_only:false; response_revision:6491; number_of_response:1; }","duration":"190.140223ms","start":"2026-08-13T08:07:11.397935Z","end":"2026-08-13T08:07:11.588075Z","steps":["trace[1272149465] 'process raft request' (duration: 190.013849ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:07:11.588273Z","caller":"traceutil/trace.go:171","msg":"trace[1853575533] linearizableReadLoop","detail":"{readStateIndex:6732; appliedIndex:6729; }","duration":"189.039075ms","start":"2026-08-13T08:07:11.399213Z","end":"2026-08-13T08:07:11.588252Z","steps":["trace[1853575533] 'read index received' (duration: 38.897336ms)","trace[1853575533] 'applied index is now lower than readState.Index' (duration: 150.140359ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:07:11.588332Z","caller":"traceutil/trace.go:171","msg":"trace[2004094612] transaction","detail":"{read_only:false; response_revision:6492; number_of_response:1; }","duration":"189.905784ms","start":"2026-08-13T08:07:11.398402Z","end":"2026-08-13T08:07:11.588308Z","steps":["trace[2004094612] 'process raft request' (duration: 189.647845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:11.588472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.229612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-13T08:07:11.588532Z","caller":"traceutil/trace.go:171","msg":"trace[477177733] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6492; }","duration":"189.315525ms","start":"2026-08-13T08:07:11.399201Z","end":"2026-08-13T08:07:11.588516Z","steps":["trace[477177733] 'agreement among raft nodes before linearized reading' (duration: 189.129539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:20.561084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.763789ms","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-08-13T08:07:20.561151Z","caller":"traceutil/trace.go:171","msg":"trace[688334214] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6540; }","duration":"942.867433ms","start":"2026-08-13T08:07:19.618271Z","end":"2026-08-13T08:07:20.561138Z","steps":["trace[688334214] 'range keys from in-memory index tree' (duration: 942.574353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:20.56114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.899146ms","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-08-13T08:07:20.561181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:19.61826Z","time spent":"942.915184ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-13T08:07:20.561218Z","caller":"traceutil/trace.go:171","msg":"trace[310527769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6540; }","duration":"944.045212ms","start":"2026-08-13T08:07:19.617151Z","end":"2026-08-13T08:07:20.561196Z","steps":["trace[310527769] 'range keys from in-memory index tree' (duration: 943.726501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:20.561316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:19.617129Z","time spent":"944.172485ms","remote":"127.0.0.1:39760","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-08-13T08:07:20.561568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.270717ms","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-08-13T08:07:20.561627Z","caller":"traceutil/trace.go:171","msg":"trace[1158682632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6540; }","duration":"343.38084ms","start":"2026-08-13T08:07:20.218232Z","end":"2026-08-13T08:07:20.561613Z","steps":["trace[1158682632] 'range keys from in-memory index tree' (duration: 343.07574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:20.562139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:20.218209Z","time spent":"343.917549ms","remote":"127.0.0.1:39760","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-08-13T08:07:20.561637Z","caller":"traceutil/trace.go:171","msg":"trace[172188895] transaction","detail":"{read_only:false; response_revision:6541; number_of_response:1; }","duration":"342.072308ms","start":"2026-08-13T08:07:20.219532Z","end":"2026-08-13T08:07:20.561605Z","steps":["trace[172188895] 'process raft request' (duration: 341.359234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:20.562359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:20.219514Z","time spent":"342.773611ms","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:07:20.561579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.017968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-13T08:07:20.562432Z","caller":"traceutil/trace.go:171","msg":"trace[1860898086] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6540; }","duration":"927.938738ms","start":"2026-08-13T08:07:19.634483Z","end":"2026-08-13T08:07:20.562422Z","steps":["trace[1860898086] 'range keys from in-memory index tree' (duration: 926.857692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:20.562518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:19.634433Z","time spent":"928.075342ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-13T08:07:27.106899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515236601572899660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:07:27.607728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515236601572899660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:07:28.173786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515236601572899660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:07:28.675698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515236601572899660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:07:29.181803Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515236601572899660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:07:29.38883Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.78679941s","expected-duration":"1s"} {"level":"info","ts":"2026-08-13T08:07:29.389265Z","caller":"traceutil/trace.go:171","msg":"trace[1096925279] transaction","detail":"{read_only:false; response_revision:6620; number_of_response:1; }","duration":"2.787476072s","start":"2026-08-13T08:07:26.601769Z","end":"2026-08-13T08:07:29.389245Z","steps":["trace[1096925279] 'process raft request' (duration: 2.787347407s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.389475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:26.601756Z","time spent":"2.787566296s","remote":"127.0.0.1:39760","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-08-13T08:07:29.438574Z","caller":"traceutil/trace.go:171","msg":"trace[743535044] transaction","detail":"{read_only:false; response_revision:6622; number_of_response:1; }","duration":"2.27691639s","start":"2026-08-13T08:07:27.161637Z","end":"2026-08-13T08:07:29.438554Z","steps":["trace[743535044] 'process raft request' (duration: 2.276850758s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.438702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:27.161622Z","time spent":"2.277016983s","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:07:29.438805Z","caller":"traceutil/trace.go:171","msg":"trace[965417727] transaction","detail":"{read_only:false; response_revision:6621; number_of_response:1; }","duration":"2.82635538s","start":"2026-08-13T08:07:26.612443Z","end":"2026-08-13T08:07:29.438798Z","steps":["trace[965417727] 'process raft request' (duration: 2.825901405s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.438843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:26.612431Z","time spent":"2.82638937s","remote":"127.0.0.1:39760","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-08-13T08:07:29.439291Z","caller":"traceutil/trace.go:171","msg":"trace[1887472032] linearizableReadLoop","detail":"{readStateIndex:6870; appliedIndex:6865; }","duration":"2.833366476s","start":"2026-08-13T08:07:26.605912Z","end":"2026-08-13T08:07:29.439279Z","steps":["trace[1887472032] 'read index received' (duration: 2.783179903s)","trace[1887472032] 'applied index is now lower than readState.Index' (duration: 50.185932ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:07:29.439261Z","caller":"traceutil/trace.go:171","msg":"trace[1025257449] transaction","detail":"{read_only:false; response_revision:6623; number_of_response:1; }","duration":"2.273462389s","start":"2026-08-13T08:07:27.165775Z","end":"2026-08-13T08:07:29.439237Z","steps":["trace[1025257449] 'process raft request' (duration: 2.272745945s)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:07:29.439413Z","caller":"traceutil/trace.go:171","msg":"trace[878663262] transaction","detail":"{read_only:false; response_revision:6624; number_of_response:1; }","duration":"908.240444ms","start":"2026-08-13T08:07:28.531166Z","end":"2026-08-13T08:07:29.439406Z","steps":["trace[878663262] 'process raft request' (duration: 907.744289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.439449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:28.531149Z","time spent":"908.281066ms","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":778,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T08:07:29.439475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:27.165761Z","time spent":"2.273584122s","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:07:29.439604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.833692096s","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-08-13T08:07:29.439629Z","caller":"traceutil/trace.go:171","msg":"trace[1480502308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6624; }","duration":"2.833737557s","start":"2026-08-13T08:07:26.605884Z","end":"2026-08-13T08:07:29.439622Z","steps":["trace[1480502308] 'agreement among raft nodes before linearized reading' (duration: 2.833651965s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.439649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:26.605876Z","time spent":"2.833767509s","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T08:07:29.439746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.827411112s","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-08-13T08:07:29.439765Z","caller":"traceutil/trace.go:171","msg":"trace[116681725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6624; }","duration":"2.827447345s","start":"2026-08-13T08:07:26.612312Z","end":"2026-08-13T08:07:29.43976Z","steps":["trace[116681725] 'agreement among raft nodes before linearized reading' (duration: 2.827392102s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.439781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:26.612306Z","time spent":"2.827471526s","remote":"127.0.0.1:39760","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-08-13T08:07:29.439962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.209306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:4 size:218494"} {"level":"info","ts":"2026-08-13T08:07:29.439985Z","caller":"traceutil/trace.go:171","msg":"trace[184075999] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:4; response_revision:6624; }","duration":"371.25762ms","start":"2026-08-13T08:07:29.06872Z","end":"2026-08-13T08:07:29.439978Z","steps":["trace[184075999] 'agreement among raft nodes before linearized reading' (duration: 371.142446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.440003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:29.068706Z","time spent":"371.29041ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":4,"response size":218516,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-08-13T08:07:29.442067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040501061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:07:29.44217Z","caller":"traceutil/trace.go:171","msg":"trace[514490063] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:6624; }","duration":"1.040637554s","start":"2026-08-13T08:07:28.401519Z","end":"2026-08-13T08:07:29.442157Z","steps":["trace[514490063] 'agreement among raft nodes before linearized reading' (duration: 1.040483099s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.44221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:28.401498Z","time spent":"1.040696316s","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":9,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:07:29.442479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.802729909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-13T08:07:29.442504Z","caller":"traceutil/trace.go:171","msg":"trace[70858634] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6624; }","duration":"1.802791412s","start":"2026-08-13T08:07:27.639705Z","end":"2026-08-13T08:07:29.442496Z","steps":["trace[70858634] 'agreement among raft nodes before linearized reading' (duration: 1.802634518s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.44253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:27.639692Z","time spent":"1.802832303s","remote":"127.0.0.1:39666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-13T08:07:29.44289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.150611542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.14\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-13T08:07:29.44298Z","caller":"traceutil/trace.go:171","msg":"trace[223741940] range","detail":"{range_begin:/registry/masterleases/199.19.213.14; range_end:; response_count:1; response_revision:6624; }","duration":"2.150692025s","start":"2026-08-13T08:07:27.292248Z","end":"2026-08-13T08:07:29.44294Z","steps":["trace[223741940] 'agreement among raft nodes before linearized reading' (duration: 2.150289662s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.443024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:27.292211Z","time spent":"2.150803019s","remote":"127.0.0.1:39510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.14\" "} {"level":"warn","ts":"2026-08-13T08:07:29.443189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.034981099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-13T08:07:29.443222Z","caller":"traceutil/trace.go:171","msg":"trace[905462513] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6624; }","duration":"2.035046901s","start":"2026-08-13T08:07:27.408163Z","end":"2026-08-13T08:07:29.44321Z","steps":["trace[905462513] 'agreement among raft nodes before linearized reading' (duration: 2.034958158s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.443257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:27.40815Z","time spent":"2.035100183s","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-13T08:07:29.443266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.927255329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-13T08:07:29.44332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.110576694s","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-08-13T08:07:29.443327Z","caller":"traceutil/trace.go:171","msg":"trace[1823783320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6624; }","duration":"1.927348994s","start":"2026-08-13T08:07:27.51596Z","end":"2026-08-13T08:07:29.443309Z","steps":["trace[1823783320] 'agreement among raft nodes before linearized reading' (duration: 1.927155667s)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:07:29.443368Z","caller":"traceutil/trace.go:171","msg":"trace[1649971428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6624; }","duration":"2.110645476s","start":"2026-08-13T08:07:27.332709Z","end":"2026-08-13T08:07:29.443354Z","steps":["trace[1649971428] 'agreement among raft nodes before linearized reading' (duration: 2.110536102s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.443384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:27.515948Z","time spent":"1.927426895s","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T08:07:29.4434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:27.332696Z","time spent":"2.110696317s","remote":"127.0.0.1:39760","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-08-13T08:07:29.443465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.837454048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:07:29.443497Z","caller":"traceutil/trace.go:171","msg":"trace[565413596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6624; }","duration":"1.837516519s","start":"2026-08-13T08:07:27.60597Z","end":"2026-08-13T08:07:29.443487Z","steps":["trace[565413596] 'agreement among raft nodes before linearized reading' (duration: 1.837449727s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:07:29.443525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:07:27.605957Z","time spent":"1.837562832s","remote":"127.0.0.1:39490","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-08-13T08:08:40.423055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515236601572900827,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-13T08:08:40.506621Z","caller":"traceutil/trace.go:171","msg":"trace[75728203] transaction","detail":"{read_only:false; response_revision:7008; number_of_response:1; }","duration":"772.099435ms","start":"2026-08-13T08:08:39.7345Z","end":"2026-08-13T08:08:40.506599Z","steps":["trace[75728203] 'process raft request' (duration: 771.904789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:40.507532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:39.734486Z","time spent":"772.229428ms","remote":"127.0.0.1:39760","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-08-13T08:08:40.51175Z","caller":"traceutil/trace.go:171","msg":"trace[1145268390] linearizableReadLoop","detail":"{readStateIndex:7271; appliedIndex:7268; }","duration":"588.950732ms","start":"2026-08-13T08:08:39.922775Z","end":"2026-08-13T08:08:40.511726Z","steps":["trace[1145268390] 'read index received' (duration: 583.572312ms)","trace[1145268390] 'applied index is now lower than readState.Index' (duration: 5.37726ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:08:40.511903Z","caller":"traceutil/trace.go:171","msg":"trace[583755476] transaction","detail":"{read_only:false; response_revision:7009; number_of_response:1; }","duration":"743.537732ms","start":"2026-08-13T08:08:39.768354Z","end":"2026-08-13T08:08:40.511891Z","steps":["trace[583755476] 'process raft request' (duration: 743.169171ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:40.512114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.319893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-13T08:08:40.512132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.273031ms","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-08-13T08:08:40.512166Z","caller":"traceutil/trace.go:171","msg":"trace[617729152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7010; }","duration":"589.406385ms","start":"2026-08-13T08:08:39.922746Z","end":"2026-08-13T08:08:40.512152Z","steps":["trace[617729152] 'agreement among raft nodes before linearized reading' (duration: 589.230539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:40.512239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:39.922731Z","time spent":"589.500749ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T08:08:40.512242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.728556ms","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-08-13T08:08:40.512278Z","caller":"traceutil/trace.go:171","msg":"trace[760942555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7010; }","duration":"587.767936ms","start":"2026-08-13T08:08:39.924491Z","end":"2026-08-13T08:08:40.512259Z","steps":["trace[760942555] 'agreement among raft nodes before linearized reading' (duration: 587.699294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:40.512253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.251684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:08:40.512372Z","caller":"traceutil/trace.go:171","msg":"trace[1921178372] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:7010; }","duration":"213.401709ms","start":"2026-08-13T08:08:40.298958Z","end":"2026-08-13T08:08:40.51236Z","steps":["trace[1921178372] 'agreement among raft nodes before linearized reading' (duration: 213.260724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:40.512401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.23198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-13T08:08:40.512442Z","caller":"traceutil/trace.go:171","msg":"trace[449792195] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7010; }","duration":"589.318933ms","start":"2026-08-13T08:08:39.923115Z","end":"2026-08-13T08:08:40.512434Z","steps":["trace[449792195] 'agreement among raft nodes before linearized reading' (duration: 589.235591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:40.512464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:39.923099Z","time spent":"589.360624ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-13T08:08:40.512504Z","caller":"traceutil/trace.go:171","msg":"trace[222404666] transaction","detail":"{read_only:false; response_revision:7010; number_of_response:1; }","duration":"588.551549ms","start":"2026-08-13T08:08:39.923915Z","end":"2026-08-13T08:08:40.512466Z","steps":["trace[222404666] 'process raft request' (duration: 587.747375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:40.512146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.085076ms","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-08-13T08:08:40.512566Z","caller":"traceutil/trace.go:171","msg":"trace[1446217486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7010; }","duration":"584.55324ms","start":"2026-08-13T08:08:39.928007Z","end":"2026-08-13T08:08:40.51256Z","steps":["trace[1446217486] 'agreement among raft nodes before linearized reading' (duration: 584.008574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:40.512591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:39.927995Z","time spent":"584.589601ms","remote":"127.0.0.1:39760","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-08-13T08:08:40.512175Z","caller":"traceutil/trace.go:171","msg":"trace[712117592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7010; }","duration":"589.354943ms","start":"2026-08-13T08:08:39.922809Z","end":"2026-08-13T08:08:40.512164Z","steps":["trace[712117592] 'agreement among raft nodes before linearized reading' (duration: 589.198158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:40.512743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.361756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T08:08:40.51276Z","caller":"traceutil/trace.go:171","msg":"trace[306539601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7010; }","duration":"575.394406ms","start":"2026-08-13T08:08:39.937361Z","end":"2026-08-13T08:08:40.512755Z","steps":["trace[306539601] 'agreement among raft nodes before linearized reading' (duration: 575.341195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:40.512775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:39.937349Z","time spent":"575.422758ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T08:08:40.512334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:39.924484Z","time spent":"587.840749ms","remote":"127.0.0.1:39760","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-08-13T08:08:40.512929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:39.922797Z","time spent":"589.981523ms","remote":"127.0.0.1:39760","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-08-13T08:08:40.513182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:39.768347Z","time spent":"743.627614ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:08:40.512681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:39.923895Z","time spent":"588.627312ms","remote":"127.0.0.1:39760","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-08-13T08:08:53.048708Z","caller":"traceutil/trace.go:171","msg":"trace[1189401775] transaction","detail":"{read_only:false; response_revision:7069; number_of_response:1; }","duration":"491.385616ms","start":"2026-08-13T08:08:52.557296Z","end":"2026-08-13T08:08:53.048682Z","steps":["trace[1189401775] 'process raft request' (duration: 491.208272ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:08:53.048703Z","caller":"traceutil/trace.go:171","msg":"trace[2018886746] transaction","detail":"{read_only:false; response_revision:7068; number_of_response:1; }","duration":"502.37765ms","start":"2026-08-13T08:08:52.546289Z","end":"2026-08-13T08:08:53.048666Z","steps":["trace[2018886746] 'process raft request' (duration: 501.940597ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:08:53.048721Z","caller":"traceutil/trace.go:171","msg":"trace[1034274935] transaction","detail":"{read_only:false; response_revision:7070; number_of_response:1; }","duration":"369.874478ms","start":"2026-08-13T08:08:52.678813Z","end":"2026-08-13T08:08:53.048687Z","steps":["trace[1034274935] 'process raft request' (duration: 369.772975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:53.048829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:52.557271Z","time spent":"491.501659ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:08:53.048873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:52.546258Z","time spent":"502.529765ms","remote":"127.0.0.1:39760","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-08-13T08:08:53.049113Z","caller":"traceutil/trace.go:171","msg":"trace[811060710] linearizableReadLoop","detail":"{readStateIndex:7333; appliedIndex:7330; }","duration":"463.468574ms","start":"2026-08-13T08:08:52.585623Z","end":"2026-08-13T08:08:53.049091Z","steps":["trace[811060710] 'read index received' (duration: 457.2366ms)","trace[811060710] 'applied index is now lower than readState.Index' (duration: 6.231234ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:08:53.049197Z","caller":"traceutil/trace.go:171","msg":"trace[1686158976] transaction","detail":"{read_only:false; response_revision:7071; number_of_response:1; }","duration":"163.815415ms","start":"2026-08-13T08:08:52.885371Z","end":"2026-08-13T08:08:53.049187Z","steps":["trace[1686158976] 'process raft request' (duration: 163.263499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:53.04937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.603948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-13T08:08:53.049363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.536421ms","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-08-13T08:08:53.0494Z","caller":"traceutil/trace.go:171","msg":"trace[1600119734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7071; }","duration":"370.670321ms","start":"2026-08-13T08:08:52.678721Z","end":"2026-08-13T08:08:53.049391Z","steps":["trace[1600119734] 'agreement among raft nodes before linearized reading' (duration: 370.552967ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:08:53.049421Z","caller":"traceutil/trace.go:171","msg":"trace[739426087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7071; }","duration":"455.608182ms","start":"2026-08-13T08:08:52.593791Z","end":"2026-08-13T08:08:53.049399Z","steps":["trace[739426087] 'agreement among raft nodes before linearized reading' (duration: 455.463008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:53.049432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:52.678701Z","time spent":"370.724421ms","remote":"127.0.0.1:39760","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-08-13T08:08:53.049454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:52.59378Z","time spent":"455.665234ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T08:08:53.049517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.894166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:08:53.049541Z","caller":"traceutil/trace.go:171","msg":"trace[2044074535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7071; }","duration":"463.947839ms","start":"2026-08-13T08:08:52.585585Z","end":"2026-08-13T08:08:53.049533Z","steps":["trace[2044074535] 'agreement among raft nodes before linearized reading' (duration: 463.910208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:53.049562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:52.585569Z","time spent":"463.98856ms","remote":"127.0.0.1:39490","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-08-13T08:08:53.049597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.298897ms","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-08-13T08:08:53.049642Z","caller":"traceutil/trace.go:171","msg":"trace[1470864890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7071; }","duration":"450.371739ms","start":"2026-08-13T08:08:52.599258Z","end":"2026-08-13T08:08:53.04963Z","steps":["trace[1470864890] 'agreement among raft nodes before linearized reading' (duration: 450.171753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:53.049643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.1641ms","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-08-13T08:08:53.049675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:52.599245Z","time spent":"450.42247ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-13T08:08:53.049681Z","caller":"traceutil/trace.go:171","msg":"trace[1981937396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7071; }","duration":"454.242642ms","start":"2026-08-13T08:08:52.595428Z","end":"2026-08-13T08:08:53.049671Z","steps":["trace[1981937396] 'agreement among raft nodes before linearized reading' (duration: 454.135489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:53.049747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:52.595408Z","time spent":"454.326625ms","remote":"127.0.0.1:39760","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-08-13T08:08:53.049778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.8438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:08:53.049814Z","caller":"traceutil/trace.go:171","msg":"trace[2139748813] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:7071; }","duration":"231.908792ms","start":"2026-08-13T08:08:52.81789Z","end":"2026-08-13T08:08:53.049799Z","steps":["trace[2139748813] 'agreement among raft nodes before linearized reading' (duration: 231.85537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:53.049901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.037703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-osd-device-details\" ","response":"range_response_count:1 size:27141"} {"level":"info","ts":"2026-08-13T08:08:53.04993Z","caller":"traceutil/trace.go:171","msg":"trace[1251252087] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-osd-device-details; range_end:; response_count:1; response_revision:7071; }","duration":"311.103075ms","start":"2026-08-13T08:08:52.738816Z","end":"2026-08-13T08:08:53.049919Z","steps":["trace[1251252087] 'agreement among raft nodes before linearized reading' (duration: 311.005722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:53.049968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:52.738785Z","time spent":"311.176316ms","remote":"127.0.0.1:39588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":27163,"request content":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-osd-device-details\" "} {"level":"warn","ts":"2026-08-13T08:08:53.049998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.800672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T08:08:53.050039Z","caller":"traceutil/trace.go:171","msg":"trace[1923250498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7071; }","duration":"449.872374ms","start":"2026-08-13T08:08:52.600156Z","end":"2026-08-13T08:08:53.050028Z","steps":["trace[1923250498] 'agreement among raft nodes before linearized reading' (duration: 449.73451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:53.050023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.49613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-08-13T08:08:53.050071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:52.600142Z","time spent":"449.921744ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-13T08:08:53.050116Z","caller":"traceutil/trace.go:171","msg":"trace[1536015705] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7071; }","duration":"455.607513ms","start":"2026-08-13T08:08:52.594481Z","end":"2026-08-13T08:08:53.050088Z","steps":["trace[1536015705] 'agreement among raft nodes before linearized reading' (duration: 455.338344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:53.050171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:52.594475Z","time spent":"455.683014ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-13T08:08:53.050407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:52.678803Z","time spent":"370.078782ms","remote":"127.0.0.1:39760","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-08-13T08:08:55.560805Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515236601572901038,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-13T08:08:55.649104Z","caller":"traceutil/trace.go:171","msg":"trace[224578497] transaction","detail":"{read_only:false; response_revision:7080; number_of_response:1; }","duration":"593.295342ms","start":"2026-08-13T08:08:55.055787Z","end":"2026-08-13T08:08:55.649083Z","steps":["trace[224578497] 'process raft request' (duration: 593.147468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:55.649253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:55.055769Z","time spent":"593.416236ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:08:55.691532Z","caller":"traceutil/trace.go:171","msg":"trace[1051260668] linearizableReadLoop","detail":"{readStateIndex:7346; appliedIndex:7343; }","duration":"631.081462ms","start":"2026-08-13T08:08:55.06043Z","end":"2026-08-13T08:08:55.691512Z","steps":["trace[1051260668] 'read index received' (duration: 588.577324ms)","trace[1051260668] 'applied index is now lower than readState.Index' (duration: 42.503098ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:08:55.691575Z","caller":"traceutil/trace.go:171","msg":"trace[102833697] transaction","detail":"{read_only:false; response_revision:7081; number_of_response:1; }","duration":"632.563307ms","start":"2026-08-13T08:08:55.058986Z","end":"2026-08-13T08:08:55.691549Z","steps":["trace[102833697] 'process raft request' (duration: 632.368621ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:08:55.691605Z","caller":"traceutil/trace.go:171","msg":"trace[1502800699] transaction","detail":"{read_only:false; response_revision:7082; number_of_response:1; }","duration":"628.757924ms","start":"2026-08-13T08:08:55.062828Z","end":"2026-08-13T08:08:55.691586Z","steps":["trace[1502800699] 'process raft request' (duration: 628.644001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:55.691689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:55.058968Z","time spent":"632.661569ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:08:55.691689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:55.062816Z","time spent":"628.822116ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:08:55.691742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.294049ms","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-08-13T08:08:55.691777Z","caller":"traceutil/trace.go:171","msg":"trace[2044031321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7082; }","duration":"631.374282ms","start":"2026-08-13T08:08:55.060392Z","end":"2026-08-13T08:08:55.691766Z","steps":["trace[2044031321] 'agreement among raft nodes before linearized reading' (duration: 631.222127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:55.691808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:55.060376Z","time spent":"631.426193ms","remote":"127.0.0.1:39760","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-08-13T08:08:55.691955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.81327ms","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-08-13T08:08:55.691963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.675082ms","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-08-13T08:08:55.692005Z","caller":"traceutil/trace.go:171","msg":"trace[671219814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7082; }","duration":"629.731143ms","start":"2026-08-13T08:08:55.062264Z","end":"2026-08-13T08:08:55.691995Z","steps":["trace[671219814] 'agreement among raft nodes before linearized reading' (duration: 629.615439ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:08:55.691986Z","caller":"traceutil/trace.go:171","msg":"trace[825217952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7082; }","duration":"622.862241ms","start":"2026-08-13T08:08:55.069115Z","end":"2026-08-13T08:08:55.691978Z","steps":["trace[825217952] 'agreement among raft nodes before linearized reading' (duration: 622.778038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:55.692028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:55.062257Z","time spent":"629.766035ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T08:08:55.692035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:55.069109Z","time spent":"622.918583ms","remote":"127.0.0.1:39760","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-08-13T08:08:55.69205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.597682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T08:08:55.692089Z","caller":"traceutil/trace.go:171","msg":"trace[847731548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7082; }","duration":"623.654354ms","start":"2026-08-13T08:08:55.068421Z","end":"2026-08-13T08:08:55.692075Z","steps":["trace[847731548] 'agreement among raft nodes before linearized reading' (duration: 623.554821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:55.692115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:55.068411Z","time spent":"623.696546ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T08:08:55.692123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.491587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T08:08:55.692129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.365626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-13T08:08:55.692172Z","caller":"traceutil/trace.go:171","msg":"trace[2111683264] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7082; }","duration":"623.429067ms","start":"2026-08-13T08:08:55.068734Z","end":"2026-08-13T08:08:55.692163Z","steps":["trace[2111683264] 'agreement among raft nodes before linearized reading' (duration: 623.352655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:55.692194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:55.068722Z","time spent":"623.464568ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-13T08:08:55.692216Z","caller":"traceutil/trace.go:171","msg":"trace[84003742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7082; }","duration":"108.555359ms","start":"2026-08-13T08:08:55.583586Z","end":"2026-08-13T08:08:55.692141Z","steps":["trace[84003742] 'agreement among raft nodes before linearized reading' (duration: 108.511999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:55.692261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.429598ms","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":"warn","ts":"2026-08-13T08:08:55.692263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.872417ms","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-08-13T08:08:55.692275Z","caller":"traceutil/trace.go:171","msg":"trace[1803711619] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:7082; }","duration":"235.468408ms","start":"2026-08-13T08:08:55.456802Z","end":"2026-08-13T08:08:55.69227Z","steps":["trace[1803711619] 'agreement among raft nodes before linearized reading' (duration: 235.442568ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:08:55.692289Z","caller":"traceutil/trace.go:171","msg":"trace[937531131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7082; }","duration":"629.92751ms","start":"2026-08-13T08:08:55.062354Z","end":"2026-08-13T08:08:55.692282Z","steps":["trace[937531131] 'agreement among raft nodes before linearized reading' (duration: 629.855498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:08:55.692311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:08:55.062338Z","time spent":"629.966251ms","remote":"127.0.0.1:39760","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-08-13T08:09:11.819651Z","caller":"traceutil/trace.go:171","msg":"trace[1876283181] transaction","detail":"{read_only:false; response_revision:7160; number_of_response:1; }","duration":"119.867982ms","start":"2026-08-13T08:09:11.699767Z","end":"2026-08-13T08:09:11.819635Z","steps":["trace[1876283181] 'process raft request' (duration: 119.755119ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:09:12.845794Z","caller":"traceutil/trace.go:171","msg":"trace[325424290] linearizableReadLoop","detail":"{readStateIndex:7436; appliedIndex:7435; }","duration":"259.87674ms","start":"2026-08-13T08:09:12.585895Z","end":"2026-08-13T08:09:12.845772Z","steps":["trace[325424290] 'read index received' (duration: 253.616629ms)","trace[325424290] 'applied index is now lower than readState.Index' (duration: 6.259141ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:09:12.845936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.031435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:09:12.845961Z","caller":"traceutil/trace.go:171","msg":"trace[1863569478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7168; }","duration":"260.098277ms","start":"2026-08-13T08:09:12.585853Z","end":"2026-08-13T08:09:12.845951Z","steps":["trace[1863569478] 'agreement among raft nodes before linearized reading' (duration: 260.026585ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:09:23.923289Z","caller":"traceutil/trace.go:171","msg":"trace[402000690] transaction","detail":"{read_only:false; response_revision:7220; number_of_response:1; }","duration":"425.49676ms","start":"2026-08-13T08:09:23.497752Z","end":"2026-08-13T08:09:23.923249Z","steps":["trace[402000690] 'process raft request' (duration: 425.344165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:09:23.923518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:09:23.49773Z","time spent":"425.632763ms","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:09:23.926506Z","caller":"traceutil/trace.go:171","msg":"trace[5261017] transaction","detail":"{read_only:false; response_revision:7221; number_of_response:1; }","duration":"239.555498ms","start":"2026-08-13T08:09:23.686929Z","end":"2026-08-13T08:09:23.926484Z","steps":["trace[5261017] 'process raft request' (duration: 239.212529ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:09:25.143259Z","caller":"traceutil/trace.go:171","msg":"trace[2134846668] transaction","detail":"{read_only:false; response_revision:7231; number_of_response:1; }","duration":"109.844404ms","start":"2026-08-13T08:09:25.03338Z","end":"2026-08-13T08:09:25.143224Z","steps":["trace[2134846668] 'process raft request' (duration: 109.679019ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:09:54.151774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5547} {"level":"info","ts":"2026-08-13T08:09:54.233443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5547,"took":"79.692259ms","hash":1911844722,"current-db-size-bytes":53854208,"current-db-size":"54 MB","current-db-size-in-use-bytes":18157568,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-13T08:09:54.233498Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1911844722,"revision":5547,"compact-revision":2875} {"level":"warn","ts":"2026-08-13T08:10:42.682288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515236601572904048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:10:43.182702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515236601572904048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:10:43.683206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515236601572904048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:10:44.189663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515236601572904048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:10:44.289996Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.108973467s","expected-duration":"1s"} {"level":"info","ts":"2026-08-13T08:10:44.291755Z","caller":"traceutil/trace.go:171","msg":"trace[1688223853] transaction","detail":"{read_only:false; response_revision:7617; number_of_response:1; }","duration":"2.110741385s","start":"2026-08-13T08:10:42.180911Z","end":"2026-08-13T08:10:44.291652Z","steps":["trace[1688223853] 'process raft request' (duration: 2.110230171s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.292198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.180892Z","time spent":"2.111070763s","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:10:44.297742Z","caller":"traceutil/trace.go:171","msg":"trace[1518401652] transaction","detail":"{read_only:false; response_revision:7619; number_of_response:1; }","duration":"2.103448927s","start":"2026-08-13T08:10:42.19426Z","end":"2026-08-13T08:10:44.297709Z","steps":["trace[1518401652] 'process raft request' (duration: 2.10315578s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.297894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.194252Z","time spent":"2.10356295s","remote":"127.0.0.1:39760","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-08-13T08:10:44.298099Z","caller":"traceutil/trace.go:171","msg":"trace[1996831100] transaction","detail":"{read_only:false; response_revision:7618; number_of_response:1; }","duration":"2.104787413s","start":"2026-08-13T08:10:42.193286Z","end":"2026-08-13T08:10:44.298074Z","steps":["trace[1996831100] 'process raft request' (duration: 2.101533465s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.29831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.193268Z","time spent":"2.104937788s","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:10:44.302689Z","caller":"traceutil/trace.go:171","msg":"trace[1895571827] linearizableReadLoop","detail":"{readStateIndex:7906; appliedIndex:7902; }","duration":"2.121464624s","start":"2026-08-13T08:10:42.181205Z","end":"2026-08-13T08:10:44.30267Z","steps":["trace[1895571827] 'read index received' (duration: 2.109180822s)","trace[1895571827] 'applied index is now lower than readState.Index' (duration: 12.282822ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:10:44.303289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.122076201s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:70 size:51763"} {"level":"info","ts":"2026-08-13T08:10:44.303319Z","caller":"traceutil/trace.go:171","msg":"trace[1731292825] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:70; response_revision:7619; }","duration":"2.122150102s","start":"2026-08-13T08:10:42.18116Z","end":"2026-08-13T08:10:44.30331Z","steps":["trace[1731292825] 'agreement among raft nodes before linearized reading' (duration: 2.121776773s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.303359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.181144Z","time spent":"2.122205704s","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":70,"response size":51785,"request content":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-08-13T08:10:44.303592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.107923808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:1 size:191"} {"level":"info","ts":"2026-08-13T08:10:44.303612Z","caller":"traceutil/trace.go:171","msg":"trace[133820692] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:1; response_revision:7619; }","duration":"2.107962968s","start":"2026-08-13T08:10:42.195642Z","end":"2026-08-13T08:10:44.303605Z","steps":["trace[133820692] 'agreement among raft nodes before linearized reading' (duration: 2.107887686s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.303628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.195634Z","time spent":"2.10798986s","remote":"127.0.0.1:39680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":213,"request content":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" "} {"level":"warn","ts":"2026-08-13T08:10:44.304925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972538677s","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-08-13T08:10:44.304954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.942337431s","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-08-13T08:10:44.304999Z","caller":"traceutil/trace.go:171","msg":"trace[1061710967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7619; }","duration":"1.942433935s","start":"2026-08-13T08:10:42.362553Z","end":"2026-08-13T08:10:44.304987Z","steps":["trace[1061710967] 'agreement among raft nodes before linearized reading' (duration: 1.94227121s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.305028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.362534Z","time spent":"1.942487805s","remote":"127.0.0.1:39760","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-08-13T08:10:44.305047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.977488161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-13T08:10:44.305071Z","caller":"traceutil/trace.go:171","msg":"trace[618891710] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7619; }","duration":"1.977553964s","start":"2026-08-13T08:10:42.32751Z","end":"2026-08-13T08:10:44.305064Z","steps":["trace[618891710] 'agreement among raft nodes before linearized reading' (duration: 1.977472612s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.30509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.327498Z","time spent":"1.977586374s","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-13T08:10:44.305212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.990459063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T08:10:44.305231Z","caller":"traceutil/trace.go:171","msg":"trace[542307885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7619; }","duration":"1.990508974s","start":"2026-08-13T08:10:42.314716Z","end":"2026-08-13T08:10:44.305225Z","steps":["trace[542307885] 'agreement among raft nodes before linearized reading' (duration: 1.990437322s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.305247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.314699Z","time spent":"1.990543574s","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T08:10:44.305288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.977936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:10:44.305316Z","caller":"traceutil/trace.go:171","msg":"trace[654002847] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:7619; }","duration":"348.033087ms","start":"2026-08-13T08:10:43.957274Z","end":"2026-08-13T08:10:44.305307Z","steps":["trace[654002847] 'agreement among raft nodes before linearized reading' (duration: 347.982035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.305349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:43.957259Z","time spent":"348.082718ms","remote":"127.0.0.1:50274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:10:44.305414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.996571067s","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-08-13T08:10:44.305453Z","caller":"traceutil/trace.go:171","msg":"trace[41342821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7619; }","duration":"1.9966378s","start":"2026-08-13T08:10:42.308809Z","end":"2026-08-13T08:10:44.305447Z","steps":["trace[41342821] 'agreement among raft nodes before linearized reading' (duration: 1.996557857s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.305473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.308796Z","time spent":"1.99667239s","remote":"127.0.0.1:39760","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-08-13T08:10:44.305503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.805052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:10:44.305527Z","caller":"traceutil/trace.go:171","msg":"trace[1767437534] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:7619; }","duration":"766.872933ms","start":"2026-08-13T08:10:43.538647Z","end":"2026-08-13T08:10:44.30552Z","steps":["trace[1767437534] 'agreement among raft nodes before linearized reading' (duration: 766.815951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.305553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:43.538627Z","time spent":"766.920144ms","remote":"127.0.0.1:50222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":21,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:10:44.305615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.04214971s","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-08-13T08:10:44.305646Z","caller":"traceutil/trace.go:171","msg":"trace[1937221202] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:7619; }","duration":"2.042214271s","start":"2026-08-13T08:10:42.263422Z","end":"2026-08-13T08:10:44.305636Z","steps":["trace[1937221202] 'agreement among raft nodes before linearized reading' (duration: 2.042152129s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.305686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.263404Z","time spent":"2.042255672s","remote":"127.0.0.1:51204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:10:44.305757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133694603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-13T08:10:44.305781Z","caller":"traceutil/trace.go:171","msg":"trace[1473536445] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7619; }","duration":"1.133746934s","start":"2026-08-13T08:10:43.172028Z","end":"2026-08-13T08:10:44.305775Z","steps":["trace[1473536445] 'agreement among raft nodes before linearized reading' (duration: 1.133672961s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.305798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:43.172012Z","time spent":"1.133782324s","remote":"127.0.0.1:39666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-13T08:10:44.305865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.062411488s","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-08-13T08:10:44.305893Z","caller":"traceutil/trace.go:171","msg":"trace[1601977932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7619; }","duration":"2.062464959s","start":"2026-08-13T08:10:42.243421Z","end":"2026-08-13T08:10:44.305886Z","steps":["trace[1601977932] 'agreement among raft nodes before linearized reading' (duration: 2.062376086s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.305927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.24341Z","time spent":"2.06251138s","remote":"127.0.0.1:39760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T08:10:44.305932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720597457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:10:44.305955Z","caller":"traceutil/trace.go:171","msg":"trace[366543040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7619; }","duration":"1.720645898s","start":"2026-08-13T08:10:42.585302Z","end":"2026-08-13T08:10:44.305948Z","steps":["trace[366543040] 'agreement among raft nodes before linearized reading' (duration: 1.720608606s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.305979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.585263Z","time spent":"1.72070958s","remote":"127.0.0.1:39490","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-08-13T08:10:44.306104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.849073659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:10:44.306131Z","caller":"traceutil/trace.go:171","msg":"trace[310806178] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:7619; }","duration":"1.849136591s","start":"2026-08-13T08:10:42.456987Z","end":"2026-08-13T08:10:44.306124Z","steps":["trace[310806178] 'agreement among raft nodes before linearized reading' (duration: 1.84908789s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.306152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.456974Z","time spent":"1.849170202s","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":6,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-08-13T08:10:44.304965Z","caller":"traceutil/trace.go:171","msg":"trace[717504620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7619; }","duration":"1.972590229s","start":"2026-08-13T08:10:42.33235Z","end":"2026-08-13T08:10:44.30494Z","steps":["trace[717504620] 'agreement among raft nodes before linearized reading' (duration: 1.972500117s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.306257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:42.332337Z","time spent":"1.973912015s","remote":"127.0.0.1:39760","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-08-13T08:10:44.305684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.161908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:10:44.306605Z","caller":"traceutil/trace.go:171","msg":"trace[1415590811] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:7619; }","duration":"866.096044ms","start":"2026-08-13T08:10:43.440482Z","end":"2026-08-13T08:10:44.306578Z","steps":["trace[1415590811] 'agreement among raft nodes before linearized reading' (duration: 865.176179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:10:44.3067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:10:43.44047Z","time spent":"866.192525ms","remote":"127.0.0.1:39652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "}