{"level":"warn","ts":"2026-07-02T15:36:23.48689Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T15:36:23.486996Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-07-02T15:36:23.487079Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T15:36:23.487099Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-02T15:36:23.487125Z","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-07-02T15:36:23.48792Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-07-02T15:36:23.488Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-07-02T15:36:23.495069Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.83434ms"} {"level":"info","ts":"2026-07-02T15:36:23.503275Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-07-02T15:36:23.503369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T15:36:23.503391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-07-02T15:36:23.503411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T15:36:23.503423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-07-02T15:36:23.503469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-07-02T15:36:23.510066Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T15:36:23.511832Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T15:36:23.513545Z","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-07-02T15:36:23.516919Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T15:36:23.517707Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T15:36:23.517851Z","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-07-02T15:36:23.517928Z","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-07-02T15:36:23.517938Z","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-07-02T15:36:23.519624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-07-02T15:36:23.519781Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-02T15:36:23.52368Z","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-07-02T15:36:23.523822Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-02T15:36:23.523865Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-02T15:36:23.524076Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T15:36:23.524137Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T15:36:23.603803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T15:36:23.603882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T15:36:23.603907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-07-02T15:36:23.603928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-07-02T15:36:23.60394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-02T15:36:23.603964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-07-02T15:36:23.603981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-02T15:36:23.605225Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T15:36:23.605228Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T15:36:23.60526Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:36:23.605293Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T15:36:23.605867Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T15:36:23.605923Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T15:36:23.60665Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:36:23.606818Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:36:23.606864Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:36:23.609209Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-07-02T15:36:23.609387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T15:36:42.251536Z","caller":"traceutil/trace.go:171","msg":"trace[852164263] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"102.69494ms","start":"2026-07-02T15:36:42.148822Z","end":"2026-07-02T15:36:42.251517Z","steps":["trace[852164263] 'compare' (duration: 96.190056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:36:42.960477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.91831ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-02T15:37:06.62169Z","caller":"traceutil/trace.go:171","msg":"trace[1039203328] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"161.279583ms","start":"2026-07-02T15:37:06.460367Z","end":"2026-07-02T15:37:06.621647Z","steps":["trace[1039203328] 'process raft request' (duration: 137.850952ms)","trace[1039203328] 'compare' (duration: 23.160746ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:37:06.687203Z","caller":"traceutil/trace.go:171","msg":"trace[12044195] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"136.925061ms","start":"2026-07-02T15:37:06.550247Z","end":"2026-07-02T15:37:06.687172Z","steps":["trace[12044195] 'process raft request' (duration: 126.710858ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:06.847467Z","caller":"traceutil/trace.go:171","msg":"trace[614426165] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"105.190357ms","start":"2026-07-02T15:37:06.742247Z","end":"2026-07-02T15:37:06.847438Z","steps":["trace[614426165] 'process raft request' (duration: 11.843619ms)","trace[614426165] 'compare' (duration: 93.213625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:37:07.406527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.609263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:37:07.406647Z","caller":"traceutil/trace.go:171","msg":"trace[67386398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"195.778156ms","start":"2026-07-02T15:37:07.210851Z","end":"2026-07-02T15:37:07.406629Z","steps":["trace[67386398] 'range keys from in-memory index tree' (duration: 195.549502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:07.406748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.161347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t42tt\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-02T15:37:07.406836Z","caller":"traceutil/trace.go:171","msg":"trace[705279029] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t42tt; range_end:; response_count:1; response_revision:629; }","duration":"241.292881ms","start":"2026-07-02T15:37:07.16552Z","end":"2026-07-02T15:37:07.406813Z","steps":["trace[705279029] 'range keys from in-memory index tree' (duration: 240.977994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:07.406987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.209218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-02T15:37:07.407035Z","caller":"traceutil/trace.go:171","msg":"trace[479846951] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:629; }","duration":"251.29662ms","start":"2026-07-02T15:37:07.155725Z","end":"2026-07-02T15:37:07.407021Z","steps":["trace[479846951] 'range keys from in-memory index tree' (duration: 250.705157ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:37:07.434792Z","caller":"traceutil/trace.go:171","msg":"trace[1162888857] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"165.828208ms","start":"2026-07-02T15:37:07.268939Z","end":"2026-07-02T15:37:07.434767Z","steps":["trace[1162888857] 'process raft request' (duration: 165.178084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:37:22.724416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.538005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T15:37:22.724542Z","caller":"traceutil/trace.go:171","msg":"trace[1710810288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"180.674028ms","start":"2026-07-02T15:37:22.543843Z","end":"2026-07-02T15:37:22.724517Z","steps":["trace[1710810288] 'range keys from in-memory index tree' (duration: 180.225119ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:38:39.438649Z","caller":"traceutil/trace.go:171","msg":"trace[463847694] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"300.539125ms","start":"2026-07-02T15:38:39.138071Z","end":"2026-07-02T15:38:39.43861Z","steps":["trace[463847694] 'read index received' (duration: 300.258179ms)","trace[463847694] 'applied index is now lower than readState.Index' (duration: 279.595µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:38:39.438755Z","caller":"traceutil/trace.go:171","msg":"trace[370218573] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"306.940819ms","start":"2026-07-02T15:38:39.131765Z","end":"2026-07-02T15:38:39.438706Z","steps":["trace[370218573] 'process raft request' (duration: 306.618883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:38:39.439072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.133424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:38:39.439141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.010374ms","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-07-02T15:38:39.439179Z","caller":"traceutil/trace.go:171","msg":"trace[1807189405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1871; }","duration":"227.315118ms","start":"2026-07-02T15:38:39.211835Z","end":"2026-07-02T15:38:39.43915Z","steps":["trace[1807189405] 'agreement among raft nodes before linearized reading' (duration: 227.145514ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:38:39.439194Z","caller":"traceutil/trace.go:171","msg":"trace[1394957610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1871; }","duration":"301.170097ms","start":"2026-07-02T15:38:39.138008Z","end":"2026-07-02T15:38:39.439178Z","steps":["trace[1394957610] 'agreement among raft nodes before linearized reading' (duration: 300.80435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:38:39.439245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:38:39.137981Z","time spent":"301.251939ms","remote":"127.0.0.1:39926","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-07-02T15:38:39.43954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:38:39.131727Z","time spent":"307.115093ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:38:39.804549Z","caller":"traceutil/trace.go:171","msg":"trace[1966687016] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"139.993136ms","start":"2026-07-02T15:38:39.664525Z","end":"2026-07-02T15:38:39.804518Z","steps":["trace[1966687016] 'process raft request' (duration: 139.74821ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:38:48.557449Z","caller":"traceutil/trace.go:171","msg":"trace[92816238] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"244.073401ms","start":"2026-07-02T15:38:48.313351Z","end":"2026-07-02T15:38:48.557424Z","steps":["trace[92816238] 'process raft request' (duration: 243.944139ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:38:48.71577Z","caller":"traceutil/trace.go:171","msg":"trace[370681907] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"129.655276ms","start":"2026-07-02T15:38:48.586048Z","end":"2026-07-02T15:38:48.715703Z","steps":["trace[370681907] 'process raft request' (duration: 68.757461ms)","trace[370681907] 'compare' (duration: 60.64957ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:38:48.803016Z","caller":"traceutil/trace.go:171","msg":"trace[229716189] linearizableReadLoop","detail":"{readStateIndex:2025; appliedIndex:2023; }","duration":"185.782512ms","start":"2026-07-02T15:38:48.617195Z","end":"2026-07-02T15:38:48.802977Z","steps":["trace[229716189] 'read index received' (duration: 37.635895ms)","trace[229716189] 'applied index is now lower than readState.Index' (duration: 148.145057ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:38:48.803121Z","caller":"traceutil/trace.go:171","msg":"trace[1267615781] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"214.974318ms","start":"2026-07-02T15:38:48.588089Z","end":"2026-07-02T15:38:48.803064Z","steps":["trace[1267615781] 'process raft request' (duration: 214.63387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:38:48.803254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.027567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:38:48.803348Z","caller":"traceutil/trace.go:171","msg":"trace[165185885] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:1954; }","duration":"186.19885ms","start":"2026-07-02T15:38:48.617128Z","end":"2026-07-02T15:38:48.803327Z","steps":["trace[165185885] 'agreement among raft nodes before linearized reading' (duration: 186.022807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:38:48.803478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.29559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:38:48.803635Z","caller":"traceutil/trace.go:171","msg":"trace[1960363837] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1954; }","duration":"130.654298ms","start":"2026-07-02T15:38:48.672934Z","end":"2026-07-02T15:38:48.803588Z","steps":["trace[1960363837] 'agreement among raft nodes before linearized reading' (duration: 130.2702ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:38:52.277528Z","caller":"traceutil/trace.go:171","msg":"trace[172330018] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"229.284691ms","start":"2026-07-02T15:38:52.048202Z","end":"2026-07-02T15:38:52.277487Z","steps":["trace[172330018] 'process raft request' (duration: 229.070186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:39:31.111605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.852578ms","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-07-02T15:39:31.111798Z","caller":"traceutil/trace.go:171","msg":"trace[1470798548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2200; }","duration":"133.080013ms","start":"2026-07-02T15:39:30.97869Z","end":"2026-07-02T15:39:31.11177Z","steps":["trace[1470798548] 'range keys from in-memory index tree' (duration: 132.654795ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:39:31.248473Z","caller":"traceutil/trace.go:171","msg":"trace[1651736994] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"132.088272ms","start":"2026-07-02T15:39:31.116348Z","end":"2026-07-02T15:39:31.248436Z","steps":["trace[1651736994] 'process raft request' (duration: 131.865557ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:39:31.504098Z","caller":"traceutil/trace.go:171","msg":"trace[1672527082] linearizableReadLoop","detail":"{readStateIndex:2281; appliedIndex:2279; }","duration":"326.369455ms","start":"2026-07-02T15:39:31.177696Z","end":"2026-07-02T15:39:31.504066Z","steps":["trace[1672527082] 'read index received' (duration: 70.474726ms)","trace[1672527082] 'applied index is now lower than readState.Index' (duration: 255.893449ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:39:31.504156Z","caller":"traceutil/trace.go:171","msg":"trace[395872580] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"387.066953ms","start":"2026-07-02T15:39:31.11705Z","end":"2026-07-02T15:39:31.504117Z","steps":["trace[395872580] 'process raft request' (duration: 386.834648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:39:31.50434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.639151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-02T15:39:31.504372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:39:31.117037Z","time spent":"387.207557ms","remote":"127.0.0.1:39926","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-07-02T15:39:31.50439Z","caller":"traceutil/trace.go:171","msg":"trace[1739322847] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2202; }","duration":"326.762744ms","start":"2026-07-02T15:39:31.17761Z","end":"2026-07-02T15:39:31.504373Z","steps":["trace[1739322847] 'agreement among raft nodes before linearized reading' (duration: 326.56909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:39:31.50444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:39:31.177582Z","time spent":"326.845106ms","remote":"127.0.0.1:35552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-02T15:40:24.839423Z","caller":"traceutil/trace.go:171","msg":"trace[366501619] transaction","detail":"{read_only:false; response_revision:2662; number_of_response:1; }","duration":"117.459455ms","start":"2026-07-02T15:40:24.721939Z","end":"2026-07-02T15:40:24.839399Z","steps":["trace[366501619] 'process raft request' (duration: 117.295582ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:27.40819Z","caller":"traceutil/trace.go:171","msg":"trace[320018914] transaction","detail":"{read_only:false; response_revision:3027; number_of_response:1; }","duration":"104.044762ms","start":"2026-07-02T15:41:27.304034Z","end":"2026-07-02T15:41:27.408079Z","steps":["trace[320018914] 'process raft request' (duration: 68.086624ms)","trace[320018914] 'compare' (duration: 35.745352ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:41:29.517516Z","caller":"traceutil/trace.go:171","msg":"trace[1602419977] transaction","detail":"{read_only:false; response_revision:3038; number_of_response:1; }","duration":"104.057462ms","start":"2026-07-02T15:41:29.413432Z","end":"2026-07-02T15:41:29.517489Z","steps":["trace[1602419977] 'process raft request' (duration: 93.981526ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:29.540813Z","caller":"traceutil/trace.go:171","msg":"trace[688840435] transaction","detail":"{read_only:false; response_revision:3039; number_of_response:1; }","duration":"126.072581ms","start":"2026-07-02T15:41:29.414707Z","end":"2026-07-02T15:41:29.54078Z","steps":["trace[688840435] 'process raft request' (duration: 125.931468ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:30.256389Z","caller":"traceutil/trace.go:171","msg":"trace[1017256842] transaction","detail":"{read_only:false; response_revision:3043; number_of_response:1; }","duration":"112.304757ms","start":"2026-07-02T15:41:30.144033Z","end":"2026-07-02T15:41:30.256338Z","steps":["trace[1017256842] 'process raft request' (duration: 111.928529ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:31.578562Z","caller":"traceutil/trace.go:171","msg":"trace[1014907366] transaction","detail":"{read_only:false; response_revision:3048; number_of_response:1; }","duration":"180.630505ms","start":"2026-07-02T15:41:31.39791Z","end":"2026-07-02T15:41:31.57854Z","steps":["trace[1014907366] 'process raft request' (duration: 180.476992ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:32.124659Z","caller":"traceutil/trace.go:171","msg":"trace[828281015] transaction","detail":"{read_only:false; response_revision:3053; number_of_response:1; }","duration":"148.713584ms","start":"2026-07-02T15:41:31.975068Z","end":"2026-07-02T15:41:32.123782Z","steps":["trace[828281015] 'process raft request' (duration: 120.38151ms)","trace[828281015] 'compare' (duration: 28.10245ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:41:33.685735Z","caller":"traceutil/trace.go:171","msg":"trace[1590205193] transaction","detail":"{read_only:false; response_revision:3059; number_of_response:1; }","duration":"101.804562ms","start":"2026-07-02T15:41:33.5839Z","end":"2026-07-02T15:41:33.685705Z","steps":["trace[1590205193] 'process raft request' (duration: 101.589638ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:33.712254Z","caller":"traceutil/trace.go:171","msg":"trace[977781799] transaction","detail":"{read_only:false; response_revision:3061; number_of_response:1; }","duration":"117.949817ms","start":"2026-07-02T15:41:33.594278Z","end":"2026-07-02T15:41:33.712228Z","steps":["trace[977781799] 'process raft request' (duration: 117.906116ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:33.712338Z","caller":"traceutil/trace.go:171","msg":"trace[998620774] transaction","detail":"{read_only:false; response_revision:3060; number_of_response:1; }","duration":"118.192563ms","start":"2026-07-02T15:41:33.594122Z","end":"2026-07-02T15:41:33.712314Z","steps":["trace[998620774] 'process raft request' (duration: 117.930557ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:34.525185Z","caller":"traceutil/trace.go:171","msg":"trace[1247371788] transaction","detail":"{read_only:false; response_revision:3064; number_of_response:1; }","duration":"142.532192ms","start":"2026-07-02T15:41:34.382626Z","end":"2026-07-02T15:41:34.525159Z","steps":["trace[1247371788] 'process raft request' (duration: 126.906919ms)","trace[1247371788] 'compare' (duration: 15.253846ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:41:35.804153Z","caller":"traceutil/trace.go:171","msg":"trace[638498062] transaction","detail":"{read_only:false; response_revision:3070; number_of_response:1; }","duration":"112.936502ms","start":"2026-07-02T15:41:35.691192Z","end":"2026-07-02T15:41:35.804128Z","steps":["trace[638498062] 'process raft request' (duration: 112.773488ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:35.865914Z","caller":"traceutil/trace.go:171","msg":"trace[324383345] transaction","detail":"{read_only:false; response_revision:3071; number_of_response:1; }","duration":"149.844049ms","start":"2026-07-02T15:41:35.716042Z","end":"2026-07-02T15:41:35.865886Z","steps":["trace[324383345] 'process raft request' (duration: 114.153317ms)","trace[324383345] 'compare' (duration: 35.497718ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:41:35.866159Z","caller":"traceutil/trace.go:171","msg":"trace[650810251] transaction","detail":"{read_only:false; response_revision:3072; number_of_response:1; }","duration":"148.848297ms","start":"2026-07-02T15:41:35.717281Z","end":"2026-07-02T15:41:35.866129Z","steps":["trace[650810251] 'process raft request' (duration: 148.564901ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:36.666738Z","caller":"traceutil/trace.go:171","msg":"trace[1845322428] transaction","detail":"{read_only:false; response_revision:3076; number_of_response:1; }","duration":"102.227222ms","start":"2026-07-02T15:41:36.564467Z","end":"2026-07-02T15:41:36.666694Z","steps":["trace[1845322428] 'process raft request' (duration: 94.175361ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:37.991226Z","caller":"traceutil/trace.go:171","msg":"trace[1180305371] transaction","detail":"{read_only:false; response_revision:3082; number_of_response:1; }","duration":"119.696465ms","start":"2026-07-02T15:41:37.871501Z","end":"2026-07-02T15:41:37.991198Z","steps":["trace[1180305371] 'process raft request' (duration: 23.730266ms)","trace[1180305371] 'compare' (duration: 95.780175ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:41:37.991396Z","caller":"traceutil/trace.go:171","msg":"trace[652641104] transaction","detail":"{read_only:false; response_revision:3083; number_of_response:1; }","duration":"119.823827ms","start":"2026-07-02T15:41:37.871542Z","end":"2026-07-02T15:41:37.991365Z","steps":["trace[652641104] 'process raft request' (duration: 119.610553ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:38.706606Z","caller":"traceutil/trace.go:171","msg":"trace[589320099] linearizableReadLoop","detail":"{readStateIndex:3200; appliedIndex:3199; }","duration":"106.225368ms","start":"2026-07-02T15:41:38.600365Z","end":"2026-07-02T15:41:38.70659Z","steps":["trace[589320099] 'read index received' (duration: 106.048654ms)","trace[589320099] 'applied index is now lower than readState.Index' (duration: 176.154µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:41:38.706689Z","caller":"traceutil/trace.go:171","msg":"trace[2075474253] transaction","detail":"{read_only:false; response_revision:3086; number_of_response:1; }","duration":"113.314049ms","start":"2026-07-02T15:41:38.593358Z","end":"2026-07-02T15:41:38.706672Z","steps":["trace[2075474253] 'process raft request' (duration: 113.128765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:41:38.706704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.32655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:41:38.70684Z","caller":"traceutil/trace.go:171","msg":"trace[1468663579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3086; }","duration":"106.486183ms","start":"2026-07-02T15:41:38.600346Z","end":"2026-07-02T15:41:38.706832Z","steps":["trace[1468663579] 'agreement among raft nodes before linearized reading' (duration: 106.329139ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:40.006905Z","caller":"traceutil/trace.go:171","msg":"trace[1210888241] transaction","detail":"{read_only:false; response_revision:3091; number_of_response:1; }","duration":"164.899781ms","start":"2026-07-02T15:41:39.841981Z","end":"2026-07-02T15:41:40.006881Z","steps":["trace[1210888241] 'process raft request' (duration: 164.741077ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:40.028023Z","caller":"traceutil/trace.go:171","msg":"trace[2018110907] transaction","detail":"{read_only:false; response_revision:3092; number_of_response:1; }","duration":"177.209972ms","start":"2026-07-02T15:41:39.850778Z","end":"2026-07-02T15:41:40.027988Z","steps":["trace[2018110907] 'process raft request' (duration: 176.958477ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:40.878764Z","caller":"traceutil/trace.go:171","msg":"trace[296425099] linearizableReadLoop","detail":"{readStateIndex:3211; appliedIndex:3210; }","duration":"150.82382ms","start":"2026-07-02T15:41:40.727915Z","end":"2026-07-02T15:41:40.878738Z","steps":["trace[296425099] 'read index received' (duration: 150.684497ms)","trace[296425099] 'applied index is now lower than readState.Index' (duration: 138.423µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:41:40.878815Z","caller":"traceutil/trace.go:171","msg":"trace[1322745338] transaction","detail":"{read_only:false; response_revision:3097; number_of_response:1; }","duration":"162.503879ms","start":"2026-07-02T15:41:40.716286Z","end":"2026-07-02T15:41:40.87879Z","steps":["trace[1322745338] 'process raft request' (duration: 162.255613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:41:40.87913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.209578ms","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-07-02T15:41:40.879177Z","caller":"traceutil/trace.go:171","msg":"trace[1231158264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3097; }","duration":"151.30446ms","start":"2026-07-02T15:41:40.727861Z","end":"2026-07-02T15:41:40.879166Z","steps":["trace[1231158264] 'agreement among raft nodes before linearized reading' (duration: 151.036824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:41:40.879271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.637066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T15:41:40.879393Z","caller":"traceutil/trace.go:171","msg":"trace[534515742] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3097; }","duration":"144.840351ms","start":"2026-07-02T15:41:40.734529Z","end":"2026-07-02T15:41:40.879369Z","steps":["trace[534515742] 'agreement among raft nodes before linearized reading' (duration: 144.419242ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:41.977182Z","caller":"traceutil/trace.go:171","msg":"trace[706455017] transaction","detail":"{read_only:false; response_revision:3102; number_of_response:1; }","duration":"104.766636ms","start":"2026-07-02T15:41:41.872382Z","end":"2026-07-02T15:41:41.977149Z","steps":["trace[706455017] 'process raft request' (duration: 104.515831ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:41.991349Z","caller":"traceutil/trace.go:171","msg":"trace[513742535] transaction","detail":"{read_only:false; response_revision:3103; number_of_response:1; }","duration":"116.985237ms","start":"2026-07-02T15:41:41.874341Z","end":"2026-07-02T15:41:41.991326Z","steps":["trace[513742535] 'process raft request' (duration: 116.838274ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:41:42.65512Z","caller":"traceutil/trace.go:171","msg":"trace[254004709] transaction","detail":"{read_only:false; response_revision:3110; number_of_response:1; }","duration":"100.917085ms","start":"2026-07-02T15:41:42.55417Z","end":"2026-07-02T15:41:42.655087Z","steps":["trace[254004709] 'process raft request' (duration: 15.602844ms)","trace[254004709] 'compare' (duration: 85.156747ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:43:51.156074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.576597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:43:51.156157Z","caller":"traceutil/trace.go:171","msg":"trace[1593712434] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:3982; }","duration":"218.767111ms","start":"2026-07-02T15:43:50.937373Z","end":"2026-07-02T15:43:51.15614Z","steps":["trace[1593712434] 'count revisions from in-memory index tree' (duration: 218.488924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:43:51.156182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.446562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:43:51.156263Z","caller":"traceutil/trace.go:171","msg":"trace[294481576] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3982; }","duration":"224.568204ms","start":"2026-07-02T15:43:50.931678Z","end":"2026-07-02T15:43:51.156246Z","steps":["trace[294481576] 'range keys from in-memory index tree' (duration: 224.38114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:43:51.15667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.884003ms","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-07-02T15:43:51.156753Z","caller":"traceutil/trace.go:171","msg":"trace[1597620922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3982; }","duration":"133.010815ms","start":"2026-07-02T15:43:51.023724Z","end":"2026-07-02T15:43:51.156735Z","steps":["trace[1597620922] 'range keys from in-memory index tree' (duration: 132.27071ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:45:19.007273Z","caller":"traceutil/trace.go:171","msg":"trace[730139534] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"101.960904ms","start":"2026-07-02T15:45:18.905238Z","end":"2026-07-02T15:45:19.007199Z","steps":["trace[730139534] 'process raft request' (duration: 71.863709ms)","trace[730139534] 'compare' (duration: 29.823409ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:45:19.930071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.686421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c576884c-7131-4de6-be16-9255079ca69d\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-02T15:45:19.930183Z","caller":"traceutil/trace.go:171","msg":"trace[1033037060] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c576884c-7131-4de6-be16-9255079ca69d; range_end:; response_count:1; response_revision:5151; }","duration":"103.844136ms","start":"2026-07-02T15:45:19.826314Z","end":"2026-07-02T15:45:19.930158Z","steps":["trace[1033037060] 'range keys from in-memory index tree' (duration: 103.494327ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:45:25.866244Z","caller":"traceutil/trace.go:171","msg":"trace[1991640325] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"102.604109ms","start":"2026-07-02T15:45:25.763602Z","end":"2026-07-02T15:45:25.866206Z","steps":["trace[1991640325] 'process raft request' (duration: 100.957603ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:45:25.926976Z","caller":"traceutil/trace.go:171","msg":"trace[571048105] linearizableReadLoop","detail":"{readStateIndex:5458; appliedIndex:5456; }","duration":"137.727471ms","start":"2026-07-02T15:45:25.789223Z","end":"2026-07-02T15:45:25.92695Z","steps":["trace[571048105] 'read index received' (duration: 75.517298ms)","trace[571048105] 'applied index is now lower than readState.Index' (duration: 62.208623ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:45:25.92725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.009666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-07-02T15:45:25.927292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.361069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:45:25.927329Z","caller":"traceutil/trace.go:171","msg":"trace[1312918840] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5265; }","duration":"138.145209ms","start":"2026-07-02T15:45:25.789158Z","end":"2026-07-02T15:45:25.927303Z","steps":["trace[1312918840] 'agreement among raft nodes before linearized reading' (duration: 137.947205ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:45:25.927347Z","caller":"traceutil/trace.go:171","msg":"trace[1837770538] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:5265; }","duration":"106.454801ms","start":"2026-07-02T15:45:25.820878Z","end":"2026-07-02T15:45:25.927333Z","steps":["trace[1837770538] 'agreement among raft nodes before linearized reading' (duration: 106.353169ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:45:25.927178Z","caller":"traceutil/trace.go:171","msg":"trace[1781473231] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"155.246076ms","start":"2026-07-02T15:45:25.771758Z","end":"2026-07-02T15:45:25.927004Z","steps":["trace[1781473231] 'process raft request' (duration: 154.99808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:45:35.065442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.640303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:45:35.065586Z","caller":"traceutil/trace.go:171","msg":"trace[1168610869] linearizableReadLoop","detail":"{readStateIndex:5623; appliedIndex:5622; }","duration":"109.730341ms","start":"2026-07-02T15:45:34.955836Z","end":"2026-07-02T15:45:35.065566Z","steps":["trace[1168610869] 'read index received' (duration: 41.591µs)","trace[1168610869] 'applied index is now lower than readState.Index' (duration: 109.68719ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:45:35.065677Z","caller":"traceutil/trace.go:171","msg":"trace[1566537619] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"205.620715ms","start":"2026-07-02T15:45:34.859992Z","end":"2026-07-02T15:45:35.065613Z","steps":["trace[1566537619] 'process raft request' (duration: 85.141784ms)","trace[1566537619] 'compare' (duration: 119.327777ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:45:35.066047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.165321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v222r\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-07-02T15:45:35.066192Z","caller":"traceutil/trace.go:171","msg":"trace[1202310692] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v222r; range_end:; response_count:1; response_revision:5425; }","duration":"110.380526ms","start":"2026-07-02T15:45:34.95578Z","end":"2026-07-02T15:45:35.06616Z","steps":["trace[1202310692] 'agreement among raft nodes before linearized reading' (duration: 109.855214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:45:45.259826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.346429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:45:45.260004Z","caller":"traceutil/trace.go:171","msg":"trace[675124178] transaction","detail":"{read_only:false; response_revision:5603; number_of_response:1; }","duration":"230.292566ms","start":"2026-07-02T15:45:45.029676Z","end":"2026-07-02T15:45:45.259968Z","steps":["trace[675124178] 'process raft request' (duration: 127.687652ms)","trace[675124178] 'compare' (duration: 102.134464ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:45:45.260583Z","caller":"traceutil/trace.go:171","msg":"trace[536150848] linearizableReadLoop","detail":"{readStateIndex:5803; appliedIndex:5802; }","duration":"198.976417ms","start":"2026-07-02T15:45:45.061594Z","end":"2026-07-02T15:45:45.260571Z","steps":["trace[536150848] 'read index received' (duration: 95.652637ms)","trace[536150848] 'applied index is now lower than readState.Index' (duration: 103.3226ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:45:45.261107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.466247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-02T15:45:45.26111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.888716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:45:45.261205Z","caller":"traceutil/trace.go:171","msg":"trace[2038068462] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5606; }","duration":"171.06816ms","start":"2026-07-02T15:45:45.090118Z","end":"2026-07-02T15:45:45.261186Z","steps":["trace[2038068462] 'agreement among raft nodes before linearized reading' (duration: 170.866536ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:45:45.261159Z","caller":"traceutil/trace.go:171","msg":"trace[760713667] transaction","detail":"{read_only:false; response_revision:5604; number_of_response:1; }","duration":"119.297752ms","start":"2026-07-02T15:45:45.141339Z","end":"2026-07-02T15:45:45.260637Z","steps":["trace[760713667] 'process raft request' (duration: 118.653838ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:45:45.261149Z","caller":"traceutil/trace.go:171","msg":"trace[781069359] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5606; }","duration":"199.561079ms","start":"2026-07-02T15:45:45.061579Z","end":"2026-07-02T15:45:45.26114Z","steps":["trace[781069359] 'agreement among raft nodes before linearized reading' (duration: 199.083099ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:46:24.171748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3008} {"level":"info","ts":"2026-07-02T15:46:24.257126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3008,"took":"83.551738ms","hash":2510231969,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":31617024,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T15:46:24.257334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2510231969,"revision":3008,"compact-revision":-1} {"level":"info","ts":"2026-07-02T15:51:24.177837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6038} {"level":"info","ts":"2026-07-02T15:51:24.27234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6038,"took":"91.416373ms","hash":2042534824,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":21852160,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-02T15:51:24.27241Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2042534824,"revision":6038,"compact-revision":3008} {"level":"info","ts":"2026-07-02T15:52:12.877341Z","caller":"traceutil/trace.go:171","msg":"trace[1616982807] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"126.471776ms","start":"2026-07-02T15:52:12.750842Z","end":"2026-07-02T15:52:12.877314Z","steps":["trace[1616982807] 'process raft request' (duration: 126.22789ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:52:12.974266Z","caller":"traceutil/trace.go:171","msg":"trace[1857162103] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"123.698324ms","start":"2026-07-02T15:52:12.850532Z","end":"2026-07-02T15:52:12.97423Z","steps":["trace[1857162103] 'process raft request' (duration: 123.606622ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:52:12.974442Z","caller":"traceutil/trace.go:171","msg":"trace[2111347170] transaction","detail":"{read_only:false; response_revision:8559; number_of_response:1; }","duration":"121.67141ms","start":"2026-07-02T15:52:12.852751Z","end":"2026-07-02T15:52:12.974423Z","steps":["trace[2111347170] 'process raft request' (duration: 121.430565ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:52:12.97453Z","caller":"traceutil/trace.go:171","msg":"trace[1228114938] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"216.730948ms","start":"2026-07-02T15:52:12.757758Z","end":"2026-07-02T15:52:12.974489Z","steps":["trace[1228114938] 'process raft request' (duration: 149.97023ms)","trace[1228114938] 'compare' (duration: 66.162975ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:52:13.962956Z","caller":"traceutil/trace.go:171","msg":"trace[1391235019] transaction","detail":"{read_only:false; response_revision:8563; number_of_response:1; }","duration":"201.03027ms","start":"2026-07-02T15:52:13.761899Z","end":"2026-07-02T15:52:13.962929Z","steps":["trace[1391235019] 'process raft request' (duration: 200.805405ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:52:22.071253Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022d1.wal"} {"level":"info","ts":"2026-07-02T15:52:42.338002Z","caller":"traceutil/trace.go:171","msg":"trace[1521113514] linearizableReadLoop","detail":"{readStateIndex:9073; appliedIndex:9072; }","duration":"183.787859ms","start":"2026-07-02T15:52:42.154182Z","end":"2026-07-02T15:52:42.33797Z","steps":["trace[1521113514] 'read index received' (duration: 183.526124ms)","trace[1521113514] 'applied index is now lower than readState.Index' (duration: 260.775µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:52:42.338406Z","caller":"traceutil/trace.go:171","msg":"trace[2101131821] transaction","detail":"{read_only:false; response_revision:8768; number_of_response:1; }","duration":"251.886265ms","start":"2026-07-02T15:52:42.086507Z","end":"2026-07-02T15:52:42.338393Z","steps":["trace[2101131821] 'process raft request' (duration: 251.282313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:52:42.33877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.744152ms","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-07-02T15:52:42.338802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.590316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-07-02T15:52:42.338802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.517196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:52:42.338878Z","caller":"traceutil/trace.go:171","msg":"trace[550101145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8768; }","duration":"153.919375ms","start":"2026-07-02T15:52:42.184935Z","end":"2026-07-02T15:52:42.338855Z","steps":["trace[550101145] 'agreement among raft nodes before linearized reading' (duration: 153.571758ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:52:42.338936Z","caller":"traceutil/trace.go:171","msg":"trace[397023082] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8768; }","duration":"184.80008ms","start":"2026-07-02T15:52:42.154109Z","end":"2026-07-02T15:52:42.338909Z","steps":["trace[397023082] 'agreement among raft nodes before linearized reading' (duration: 184.410362ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:52:42.338975Z","caller":"traceutil/trace.go:171","msg":"trace[364556493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8768; }","duration":"127.778072ms","start":"2026-07-02T15:52:42.211151Z","end":"2026-07-02T15:52:42.338929Z","steps":["trace[364556493] 'agreement among raft nodes before linearized reading' (duration: 127.455095ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:52:42.514895Z","caller":"traceutil/trace.go:171","msg":"trace[1053741434] transaction","detail":"{read_only:false; response_revision:8769; number_of_response:1; }","duration":"169.465449ms","start":"2026-07-02T15:52:42.345405Z","end":"2026-07-02T15:52:42.514871Z","steps":["trace[1053741434] 'process raft request' (duration: 154.492077ms)","trace[1053741434] 'compare' (duration: 14.266727ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:52:46.645777Z","caller":"traceutil/trace.go:171","msg":"trace[855618637] linearizableReadLoop","detail":"{readStateIndex:9098; appliedIndex:9097; }","duration":"101.600408ms","start":"2026-07-02T15:52:46.54415Z","end":"2026-07-02T15:52:46.645751Z","steps":["trace[855618637] 'read index received' (duration: 101.370683ms)","trace[855618637] 'applied index is now lower than readState.Index' (duration: 228.445µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:52:46.64581Z","caller":"traceutil/trace.go:171","msg":"trace[1253282823] transaction","detail":"{read_only:false; response_revision:8791; number_of_response:1; }","duration":"109.444997ms","start":"2026-07-02T15:52:46.536324Z","end":"2026-07-02T15:52:46.645769Z","steps":["trace[1253282823] 'process raft request' (duration: 109.228252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:52:46.646208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.993836ms","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-07-02T15:52:46.646346Z","caller":"traceutil/trace.go:171","msg":"trace[862836838] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8791; }","duration":"102.19203ms","start":"2026-07-02T15:52:46.544124Z","end":"2026-07-02T15:52:46.646316Z","steps":["trace[862836838] 'agreement among raft nodes before linearized reading' (duration: 101.798881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:52:46.887417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.609877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:52:46.88754Z","caller":"traceutil/trace.go:171","msg":"trace[1692010728] linearizableReadLoop","detail":"{readStateIndex:9099; appliedIndex:9098; }","duration":"178.648598ms","start":"2026-07-02T15:52:46.708872Z","end":"2026-07-02T15:52:46.88752Z","steps":["trace[1692010728] 'read index received' (duration: 70.808716ms)","trace[1692010728] 'applied index is now lower than readState.Index' (duration: 107.838412ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:52:46.887635Z","caller":"traceutil/trace.go:171","msg":"trace[2088152935] transaction","detail":"{read_only:false; response_revision:8792; number_of_response:1; }","duration":"236.36201ms","start":"2026-07-02T15:52:46.651261Z","end":"2026-07-02T15:52:46.887623Z","steps":["trace[2088152935] 'process raft request' (duration: 128.446436ms)","trace[2088152935] 'compare' (duration: 107.238069ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:52:46.887864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.002716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T15:52:46.888225Z","caller":"traceutil/trace.go:171","msg":"trace[676776611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8792; }","duration":"179.407884ms","start":"2026-07-02T15:52:46.708804Z","end":"2026-07-02T15:52:46.888212Z","steps":["trace[676776611] 'agreement among raft nodes before linearized reading' (duration: 178.939694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:52:46.888212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.518889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-02T15:52:46.888289Z","caller":"traceutil/trace.go:171","msg":"trace[420202123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8792; }","duration":"109.701703ms","start":"2026-07-02T15:52:46.77857Z","end":"2026-07-02T15:52:46.888272Z","steps":["trace[420202123] 'agreement among raft nodes before linearized reading' (duration: 109.243843ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:52:55.529479Z","caller":"traceutil/trace.go:171","msg":"trace[1414425616] transaction","detail":"{read_only:false; response_revision:8882; number_of_response:1; }","duration":"106.921483ms","start":"2026-07-02T15:52:55.422493Z","end":"2026-07-02T15:52:55.529414Z","steps":["trace[1414425616] 'process raft request' (duration: 106.553726ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:54:15.577621Z","caller":"traceutil/trace.go:171","msg":"trace[127500451] transaction","detail":"{read_only:false; response_revision:9651; number_of_response:1; }","duration":"220.778077ms","start":"2026-07-02T15:54:15.35681Z","end":"2026-07-02T15:54:15.577589Z","steps":["trace[127500451] 'process raft request' (duration: 220.536272ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:54:15.601761Z","caller":"traceutil/trace.go:171","msg":"trace[613135632] linearizableReadLoop","detail":"{readStateIndex:9986; appliedIndex:9984; }","duration":"198.998918ms","start":"2026-07-02T15:54:15.402738Z","end":"2026-07-02T15:54:15.601737Z","steps":["trace[613135632] 'read index received' (duration: 174.590723ms)","trace[613135632] 'applied index is now lower than readState.Index' (duration: 24.406985ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:54:15.601957Z","caller":"traceutil/trace.go:171","msg":"trace[903410081] transaction","detail":"{read_only:false; response_revision:9652; number_of_response:1; }","duration":"244.54723ms","start":"2026-07-02T15:54:15.35736Z","end":"2026-07-02T15:54:15.601907Z","steps":["trace[903410081] 'process raft request' (duration: 244.229323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:54:15.602112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.360295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-02T15:54:15.602201Z","caller":"traceutil/trace.go:171","msg":"trace[413613583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9652; }","duration":"199.508279ms","start":"2026-07-02T15:54:15.402679Z","end":"2026-07-02T15:54:15.602187Z","steps":["trace[413613583] 'agreement among raft nodes before linearized reading' (duration: 199.223423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:54:15.640587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.967345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T15:54:15.640698Z","caller":"traceutil/trace.go:171","msg":"trace[1869794505] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9655; }","duration":"236.118188ms","start":"2026-07-02T15:54:15.404558Z","end":"2026-07-02T15:54:15.640676Z","steps":["trace[1869794505] 'agreement among raft nodes before linearized reading' (duration: 235.851653ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:54:15.72047Z","caller":"traceutil/trace.go:171","msg":"trace[1565532068] transaction","detail":"{read_only:false; response_revision:9656; number_of_response:1; }","duration":"113.926415ms","start":"2026-07-02T15:54:15.60651Z","end":"2026-07-02T15:54:15.720437Z","steps":["trace[1565532068] 'process raft request' (duration: 113.69084ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:54:17.648776Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T15:54:17.653282Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-07-02T15:54:17.653399Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-07-02T15:56:24.185469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8127} {"level":"info","ts":"2026-07-02T15:56:24.250728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8127,"took":"63.348597ms","hash":846266820,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":28790784,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-02T15:56:24.250791Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":846266820,"revision":8127,"compact-revision":6038} {"level":"info","ts":"2026-07-02T15:59:35.655486Z","caller":"traceutil/trace.go:171","msg":"trace[256496777] transaction","detail":"{read_only:false; response_revision:11980; number_of_response:1; }","duration":"162.917555ms","start":"2026-07-02T15:59:35.492526Z","end":"2026-07-02T15:59:35.655444Z","steps":["trace[256496777] 'process raft request' (duration: 162.620829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:59:59.373139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.981224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:59:59.373249Z","caller":"traceutil/trace.go:171","msg":"trace[243891648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12113; }","duration":"161.100767ms","start":"2026-07-02T15:59:59.212125Z","end":"2026-07-02T15:59:59.373226Z","steps":["trace[243891648] 'range keys from in-memory index tree' (duration: 160.844831ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:59:59.901518Z","caller":"traceutil/trace.go:171","msg":"trace[1329808589] transaction","detail":"{read_only:false; response_revision:12116; number_of_response:1; }","duration":"130.903184ms","start":"2026-07-02T15:59:59.770538Z","end":"2026-07-02T15:59:59.901441Z","steps":["trace[1329808589] 'process raft request' (duration: 130.436664ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:01:24.192938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10539} {"level":"info","ts":"2026-07-02T16:01:24.27831Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10539,"took":"83.351564ms","hash":4046680994,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":26267648,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-02T16:01:24.278386Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4046680994,"revision":10539,"compact-revision":8127} {"level":"info","ts":"2026-07-02T16:06:24.199216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12663} {"level":"info","ts":"2026-07-02T16:06:24.27572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12663,"took":"73.850613ms","hash":1149396052,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":26947584,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-02T16:06:24.275795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1149396052,"revision":12663,"compact-revision":10539} {"level":"info","ts":"2026-07-02T16:06:30.590839Z","caller":"traceutil/trace.go:171","msg":"trace[1213369799] transaction","detail":"{read_only:false; response_revision:14770; number_of_response:1; }","duration":"136.46469ms","start":"2026-07-02T16:06:30.454346Z","end":"2026-07-02T16:06:30.59081Z","steps":["trace[1213369799] 'process raft request' (duration: 133.70398ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:06:30.608819Z","caller":"traceutil/trace.go:171","msg":"trace[1855258272] transaction","detail":"{read_only:false; response_revision:14771; number_of_response:1; }","duration":"139.384423ms","start":"2026-07-02T16:06:30.469408Z","end":"2026-07-02T16:06:30.608793Z","steps":["trace[1855258272] 'process raft request' (duration: 139.23672ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:06:31.66753Z","caller":"traceutil/trace.go:171","msg":"trace[1513708768] linearizableReadLoop","detail":"{readStateIndex:15280; appliedIndex:15279; }","duration":"192.390663ms","start":"2026-07-02T16:06:31.475109Z","end":"2026-07-02T16:06:31.6675Z","steps":["trace[1513708768] 'read index received' (duration: 192.147807ms)","trace[1513708768] 'applied index is now lower than readState.Index' (duration: 241.866µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:06:31.667767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.647148ms","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-07-02T16:06:31.667808Z","caller":"traceutil/trace.go:171","msg":"trace[1893753929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14774; }","duration":"192.76488ms","start":"2026-07-02T16:06:31.475032Z","end":"2026-07-02T16:06:31.667797Z","steps":["trace[1893753929] 'agreement among raft nodes before linearized reading' (duration: 192.582206ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:06:31.668202Z","caller":"traceutil/trace.go:171","msg":"trace[621665425] transaction","detail":"{read_only:false; response_revision:14774; number_of_response:1; }","duration":"270.16399ms","start":"2026-07-02T16:06:31.398026Z","end":"2026-07-02T16:06:31.66819Z","steps":["trace[621665425] 'process raft request' (duration: 269.301841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:06:54.224355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.754823ms","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-07-02T16:06:54.224438Z","caller":"traceutil/trace.go:171","msg":"trace[1850763448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15081; }","duration":"134.889906ms","start":"2026-07-02T16:06:54.089532Z","end":"2026-07-02T16:06:54.224422Z","steps":["trace[1850763448] 'range keys from in-memory index tree' (duration: 134.595309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:06:54.224433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.682893ms","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-07-02T16:06:54.22451Z","caller":"traceutil/trace.go:171","msg":"trace[2125903071] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15081; }","duration":"129.767636ms","start":"2026-07-02T16:06:54.094723Z","end":"2026-07-02T16:06:54.224491Z","steps":["trace[2125903071] 'range keys from in-memory index tree' (duration: 129.52755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:06:54.224614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.133432ms","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-07-02T16:06:54.224649Z","caller":"traceutil/trace.go:171","msg":"trace[1114064398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15081; }","duration":"135.205954ms","start":"2026-07-02T16:06:54.089431Z","end":"2026-07-02T16:06:54.224637Z","steps":["trace[1114064398] 'range keys from in-memory index tree' (duration: 135.03661ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:08:12.606527Z","caller":"traceutil/trace.go:171","msg":"trace[1456163393] linearizableReadLoop","detail":"{readStateIndex:16387; appliedIndex:16386; }","duration":"301.54947ms","start":"2026-07-02T16:08:12.30495Z","end":"2026-07-02T16:08:12.6065Z","steps":["trace[1456163393] 'read index received' (duration: 301.424278ms)","trace[1456163393] 'applied index is now lower than readState.Index' (duration: 123.802µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:08:12.606593Z","caller":"traceutil/trace.go:171","msg":"trace[611897447] transaction","detail":"{read_only:false; response_revision:15848; number_of_response:1; }","duration":"336.570741ms","start":"2026-07-02T16:08:12.269991Z","end":"2026-07-02T16:08:12.606562Z","steps":["trace[611897447] 'process raft request' (duration: 336.330146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:08:12.606696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.726074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:08:12.606729Z","caller":"traceutil/trace.go:171","msg":"trace[1381048954] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15848; }","duration":"301.814786ms","start":"2026-07-02T16:08:12.304906Z","end":"2026-07-02T16:08:12.606721Z","steps":["trace[1381048954] 'agreement among raft nodes before linearized reading' (duration: 301.733434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:08:12.606768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:08:12.304879Z","time spent":"301.879607ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-02T16:08:12.606775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:08:12.269968Z","time spent":"336.707553ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:08:12.676971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.15302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-02T16:08:12.677158Z","caller":"traceutil/trace.go:171","msg":"trace[1211699673] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15848; }","duration":"365.436187ms","start":"2026-07-02T16:08:12.311688Z","end":"2026-07-02T16:08:12.677124Z","steps":["trace[1211699673] 'agreement among raft nodes before linearized reading' (duration: 365.035707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:08:12.677214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.976289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-07-02T16:08:12.677236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:08:12.311637Z","time spent":"365.58134ms","remote":"127.0.0.1:39804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-07-02T16:08:12.677239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.591589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:08:12.67728Z","caller":"traceutil/trace.go:171","msg":"trace[1516751446] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15848; }","duration":"366.082201ms","start":"2026-07-02T16:08:12.311181Z","end":"2026-07-02T16:08:12.677263Z","steps":["trace[1516751446] 'agreement among raft nodes before linearized reading' (duration: 365.869046ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:08:12.677299Z","caller":"traceutil/trace.go:171","msg":"trace[1189691809] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15848; }","duration":"364.690631ms","start":"2026-07-02T16:08:12.312596Z","end":"2026-07-02T16:08:12.677286Z","steps":["trace[1189691809] 'agreement among raft nodes before linearized reading' (duration: 364.596319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:08:12.677328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:08:12.312578Z","time spent":"364.744842ms","remote":"127.0.0.1:39804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":27,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-02T16:08:12.677337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:08:12.311164Z","time spent":"366.156812ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5470,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-02T16:08:12.676971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.342231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-07-02T16:08:12.67748Z","caller":"traceutil/trace.go:171","msg":"trace[1102754256] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15848; }","duration":"369.939164ms","start":"2026-07-02T16:08:12.307522Z","end":"2026-07-02T16:08:12.677461Z","steps":["trace[1102754256] 'agreement among raft nodes before linearized reading' (duration: 369.156476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:08:12.677531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:08:12.307494Z","time spent":"370.022045ms","remote":"127.0.0.1:39804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":703,"request content":"key:\"/registry/services/endpoints/openstack/nova-metadata\" "} {"level":"info","ts":"2026-07-02T16:11:24.236648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14720} {"level":"info","ts":"2026-07-02T16:11:24.343614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14720,"took":"103.409855ms","hash":491234195,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":33804288,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-02T16:11:24.34367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":491234195,"revision":14720,"compact-revision":12663} {"level":"info","ts":"2026-07-02T16:14:22.800226Z","caller":"traceutil/trace.go:171","msg":"trace[429445992] linearizableReadLoop","detail":"{readStateIndex:19180; appliedIndex:19178; }","duration":"235.327514ms","start":"2026-07-02T16:14:22.564867Z","end":"2026-07-02T16:14:22.800195Z","steps":["trace[429445992] 'read index received' (duration: 24.507463ms)","trace[429445992] 'applied index is now lower than readState.Index' (duration: 210.788251ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:14:22.800804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.924827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-02T16:14:22.800901Z","caller":"traceutil/trace.go:171","msg":"trace[1145807109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18548; }","duration":"236.07489ms","start":"2026-07-02T16:14:22.56481Z","end":"2026-07-02T16:14:22.800884Z","steps":["trace[1145807109] 'agreement among raft nodes before linearized reading' (duration: 235.568659ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:14:22.801795Z","caller":"traceutil/trace.go:171","msg":"trace[1683984804] transaction","detail":"{read_only:false; response_revision:18548; number_of_response:1; }","duration":"237.00606ms","start":"2026-07-02T16:14:22.564765Z","end":"2026-07-02T16:14:22.801771Z","steps":["trace[1683984804] 'process raft request' (duration: 235.159411ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:14:33.806683Z","caller":"traceutil/trace.go:171","msg":"trace[1631238795] transaction","detail":"{read_only:false; response_revision:18757; number_of_response:1; }","duration":"138.445069ms","start":"2026-07-02T16:14:33.668217Z","end":"2026-07-02T16:14:33.806662Z","steps":["trace[1631238795] 'process raft request' (duration: 138.314266ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:15:36.736672Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T16:15:36.742145Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-07-02T16:15:36.742228Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-07-02T16:16:24.243407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17366} {"level":"info","ts":"2026-07-02T16:16:24.345018Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17366,"took":"98.088852ms","hash":1042288487,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":34254848,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-02T16:16:24.34509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1042288487,"revision":17366,"compact-revision":14720} {"level":"info","ts":"2026-07-02T16:20:03.717528Z","caller":"traceutil/trace.go:171","msg":"trace[958299577] linearizableReadLoop","detail":"{readStateIndex:21908; appliedIndex:21907; }","duration":"161.033019ms","start":"2026-07-02T16:20:03.556466Z","end":"2026-07-02T16:20:03.717499Z","steps":["trace[958299577] 'read index received' (duration: 88.170566ms)","trace[958299577] 'applied index is now lower than readState.Index' (duration: 72.861093ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:20:03.7177Z","caller":"traceutil/trace.go:171","msg":"trace[916160374] transaction","detail":"{read_only:false; response_revision:21188; number_of_response:1; }","duration":"274.194268ms","start":"2026-07-02T16:20:03.443482Z","end":"2026-07-02T16:20:03.717676Z","steps":["trace[916160374] 'process raft request' (duration: 201.13188ms)","trace[916160374] 'compare' (duration: 72.771542ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:20:03.717736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.247774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T16:20:03.717793Z","caller":"traceutil/trace.go:171","msg":"trace[18759979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21188; }","duration":"161.358256ms","start":"2026-07-02T16:20:03.556417Z","end":"2026-07-02T16:20:03.717776Z","steps":["trace[18759979] 'agreement among raft nodes before linearized reading' (duration: 161.185112ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:20:03.753738Z","caller":"traceutil/trace.go:171","msg":"trace[233386770] transaction","detail":"{read_only:false; response_revision:21189; number_of_response:1; }","duration":"136.541917ms","start":"2026-07-02T16:20:03.617166Z","end":"2026-07-02T16:20:03.753707Z","steps":["trace[233386770] 'process raft request' (duration: 136.392124ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:20:03.995706Z","caller":"traceutil/trace.go:171","msg":"trace[1402904719] transaction","detail":"{read_only:false; response_revision:21190; number_of_response:1; }","duration":"286.776263ms","start":"2026-07-02T16:20:03.708899Z","end":"2026-07-02T16:20:03.995676Z","steps":["trace[1402904719] 'process raft request' (duration: 286.539258ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:20:03.995898Z","caller":"traceutil/trace.go:171","msg":"trace[1920508302] transaction","detail":"{read_only:false; response_revision:21191; number_of_response:1; }","duration":"274.26448ms","start":"2026-07-02T16:20:03.721596Z","end":"2026-07-02T16:20:03.99586Z","steps":["trace[1920508302] 'process raft request' (duration: 274.037235ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:20:04.36976Z","caller":"traceutil/trace.go:171","msg":"trace[1130571847] transaction","detail":"{read_only:false; response_revision:21193; number_of_response:1; }","duration":"102.443436ms","start":"2026-07-02T16:20:04.26728Z","end":"2026-07-02T16:20:04.369724Z","steps":["trace[1130571847] 'process raft request' (duration: 102.227452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:20:04.661091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.734243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-02T16:20:04.661153Z","caller":"traceutil/trace.go:171","msg":"trace[1552194333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21193; }","duration":"229.870016ms","start":"2026-07-02T16:20:04.431269Z","end":"2026-07-02T16:20:04.661139Z","steps":["trace[1552194333] 'range keys from in-memory index tree' (duration: 229.579719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:20:04.661941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.469874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-02T16:20:04.662025Z","caller":"traceutil/trace.go:171","msg":"trace[1737618586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21193; }","duration":"181.563086ms","start":"2026-07-02T16:20:04.480439Z","end":"2026-07-02T16:20:04.662002Z","steps":["trace[1737618586] 'range keys from in-memory index tree' (duration: 180.665516ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:21:24.248913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19700} {"level":"info","ts":"2026-07-02T16:21:24.365954Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19700,"took":"113.908945ms","hash":1995744251,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":29691904,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T16:21:24.366102Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1995744251,"revision":19700,"compact-revision":17366} {"level":"info","ts":"2026-07-02T16:23:16.124951Z","caller":"traceutil/trace.go:171","msg":"trace[1534681669] transaction","detail":"{read_only:false; response_revision:22788; number_of_response:1; }","duration":"145.490521ms","start":"2026-07-02T16:23:15.979419Z","end":"2026-07-02T16:23:16.124909Z","steps":["trace[1534681669] 'process raft request' (duration: 145.063242ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:24:05.4577Z","caller":"traceutil/trace.go:171","msg":"trace[896640035] linearizableReadLoop","detail":"{readStateIndex:24479; appliedIndex:24478; }","duration":"192.302999ms","start":"2026-07-02T16:24:05.265365Z","end":"2026-07-02T16:24:05.457668Z","steps":["trace[896640035] 'read index received' (duration: 192.214257ms)","trace[896640035] 'applied index is now lower than readState.Index' (duration: 87.352µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:24:05.457832Z","caller":"traceutil/trace.go:171","msg":"trace[1123451806] transaction","detail":"{read_only:false; response_revision:23686; number_of_response:1; }","duration":"204.588546ms","start":"2026-07-02T16:24:05.253193Z","end":"2026-07-02T16:24:05.457781Z","steps":["trace[1123451806] 'process raft request' (duration: 204.274379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:24:05.457987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.608676ms","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-07-02T16:24:05.458063Z","caller":"traceutil/trace.go:171","msg":"trace[1740404144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23686; }","duration":"192.744839ms","start":"2026-07-02T16:24:05.2653Z","end":"2026-07-02T16:24:05.458044Z","steps":["trace[1740404144] 'agreement among raft nodes before linearized reading' (duration: 192.506024ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:24:05.503766Z","caller":"traceutil/trace.go:171","msg":"trace[199968483] transaction","detail":"{read_only:false; response_revision:23687; number_of_response:1; }","duration":"193.780541ms","start":"2026-07-02T16:24:05.309958Z","end":"2026-07-02T16:24:05.503739Z","steps":["trace[199968483] 'process raft request' (duration: 167.46412ms)","trace[199968483] 'compare' (duration: 26.117227ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:26:24.258957Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21918} {"level":"info","ts":"2026-07-02T16:26:24.372308Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21918,"took":"109.444817ms","hash":3256131443,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":44732416,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-07-02T16:26:24.372372Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3256131443,"revision":21918,"compact-revision":19700} {"level":"info","ts":"2026-07-02T16:27:23.687121Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000674f.wal"} {"level":"warn","ts":"2026-07-02T16:27:53.372996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.763272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:27:53.373204Z","caller":"traceutil/trace.go:171","msg":"trace[2051059748] linearizableReadLoop","detail":"{readStateIndex:26798; appliedIndex:26796; }","duration":"156.228285ms","start":"2026-07-02T16:27:53.216958Z","end":"2026-07-02T16:27:53.373186Z","steps":["trace[2051059748] 'read index received' (duration: 18.185645ms)","trace[2051059748] 'applied index is now lower than readState.Index' (duration: 138.04179ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:27:53.373274Z","caller":"traceutil/trace.go:171","msg":"trace[635545456] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"167.429887ms","start":"2026-07-02T16:27:53.205835Z","end":"2026-07-02T16:27:53.373265Z","steps":["trace[635545456] 'process raft request' (duration: 167.293945ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:27:53.373349Z","caller":"traceutil/trace.go:171","msg":"trace[64200289] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"172.960578ms","start":"2026-07-02T16:27:53.200336Z","end":"2026-07-02T16:27:53.373297Z","steps":["trace[64200289] 'process raft request' (duration: 34.794626ms)","trace[64200289] 'compare' (duration: 136.910204ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:27:53.373474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.517081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-02T16:27:53.373514Z","caller":"traceutil/trace.go:171","msg":"trace[1573003023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25946; }","duration":"156.602713ms","start":"2026-07-02T16:27:53.216901Z","end":"2026-07-02T16:27:53.373504Z","steps":["trace[1573003023] 'agreement among raft nodes before linearized reading' (duration: 156.46523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:27:53.379825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.743196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:27:53.379884Z","caller":"traceutil/trace.go:171","msg":"trace[1721883021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25947; }","duration":"157.847029ms","start":"2026-07-02T16:27:53.222024Z","end":"2026-07-02T16:27:53.379871Z","steps":["trace[1721883021] 'agreement among raft nodes before linearized reading' (duration: 157.701356ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:27:53.550101Z","caller":"traceutil/trace.go:171","msg":"trace[298426517] linearizableReadLoop","detail":"{readStateIndex:26800; appliedIndex:26799; }","duration":"169.192666ms","start":"2026-07-02T16:27:53.380885Z","end":"2026-07-02T16:27:53.550078Z","steps":["trace[298426517] 'read index received' (duration: 156.484179ms)","trace[298426517] 'applied index is now lower than readState.Index' (duration: 12.707807ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:27:53.550299Z","caller":"traceutil/trace.go:171","msg":"trace[45777769] transaction","detail":"{read_only:false; response_revision:25948; number_of_response:1; }","duration":"172.294653ms","start":"2026-07-02T16:27:53.377993Z","end":"2026-07-02T16:27:53.550288Z","steps":["trace[45777769] 'process raft request' (duration: 159.356942ms)","trace[45777769] 'compare' (duration: 12.4041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:27:53.550561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.584155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:27:53.550672Z","caller":"traceutil/trace.go:171","msg":"trace[1302652314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25948; }","duration":"169.772329ms","start":"2026-07-02T16:27:53.380865Z","end":"2026-07-02T16:27:53.550637Z","steps":["trace[1302652314] 'agreement among raft nodes before linearized reading' (duration: 169.501673ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:27:53.752737Z","caller":"traceutil/trace.go:171","msg":"trace[515514925] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"294.344445ms","start":"2026-07-02T16:27:53.458358Z","end":"2026-07-02T16:27:53.752702Z","steps":["trace[515514925] 'process raft request' (duration: 228.288389ms)","trace[515514925] 'compare' (duration: 65.644347ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:27:53.753623Z","caller":"traceutil/trace.go:171","msg":"trace[122873901] linearizableReadLoop","detail":"{readStateIndex:26802; appliedIndex:26800; }","duration":"203.436649ms","start":"2026-07-02T16:27:53.55016Z","end":"2026-07-02T16:27:53.753596Z","steps":["trace[122873901] 'read index received' (duration: 136.558456ms)","trace[122873901] 'applied index is now lower than readState.Index' (duration: 66.877503ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:27:53.753711Z","caller":"traceutil/trace.go:171","msg":"trace[104237557] transaction","detail":"{read_only:false; response_revision:25950; number_of_response:1; }","duration":"207.300474ms","start":"2026-07-02T16:27:53.546374Z","end":"2026-07-02T16:27:53.753674Z","steps":["trace[104237557] 'process raft request' (duration: 207.12956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:27:53.753761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.227153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T16:27:53.753842Z","caller":"traceutil/trace.go:171","msg":"trace[823032976] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25950; }","duration":"283.361586ms","start":"2026-07-02T16:27:53.470456Z","end":"2026-07-02T16:27:53.753818Z","steps":["trace[823032976] 'agreement among raft nodes before linearized reading' (duration: 283.221323ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:27:57.869458Z","caller":"traceutil/trace.go:171","msg":"trace[1576031289] transaction","detail":"{read_only:false; response_revision:25977; number_of_response:1; }","duration":"107.23625ms","start":"2026-07-02T16:27:57.762197Z","end":"2026-07-02T16:27:57.869433Z","steps":["trace[1576031289] 'process raft request' (duration: 107.010644ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:27:57.879386Z","caller":"traceutil/trace.go:171","msg":"trace[1703035572] transaction","detail":"{read_only:false; response_revision:25978; number_of_response:1; }","duration":"114.560779ms","start":"2026-07-02T16:27:57.764808Z","end":"2026-07-02T16:27:57.879369Z","steps":["trace[1703035572] 'process raft request' (duration: 114.439856ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:27:58.142064Z","caller":"traceutil/trace.go:171","msg":"trace[1808829509] transaction","detail":"{read_only:false; response_revision:25979; number_of_response:1; }","duration":"159.792451ms","start":"2026-07-02T16:27:57.982225Z","end":"2026-07-02T16:27:58.142018Z","steps":["trace[1808829509] 'process raft request' (duration: 142.452485ms)","trace[1808829509] 'compare' (duration: 16.709313ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:27:58.896498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.463615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:27:58.896691Z","caller":"traceutil/trace.go:171","msg":"trace[1401690083] linearizableReadLoop","detail":"{readStateIndex:26836; appliedIndex:26833; }","duration":"140.36097ms","start":"2026-07-02T16:27:58.756316Z","end":"2026-07-02T16:27:58.896676Z","steps":["trace[1401690083] 'read index received' (duration: 3.740782ms)","trace[1401690083] 'applied index is now lower than readState.Index' (duration: 136.619408ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:27:58.897197Z","caller":"traceutil/trace.go:171","msg":"trace[231787405] transaction","detail":"{read_only:false; response_revision:25982; number_of_response:1; }","duration":"420.918354ms","start":"2026-07-02T16:27:58.476242Z","end":"2026-07-02T16:27:58.89716Z","steps":["trace[231787405] 'process raft request' (duration: 420.363972ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:27:58.897203Z","caller":"traceutil/trace.go:171","msg":"trace[1322122126] transaction","detail":"{read_only:false; response_revision:25983; number_of_response:1; }","duration":"359.898288ms","start":"2026-07-02T16:27:58.537272Z","end":"2026-07-02T16:27:58.89717Z","steps":["trace[1322122126] 'process raft request' (duration: 359.374287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:27:58.897289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.354254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T16:27:58.897337Z","caller":"traceutil/trace.go:171","msg":"trace[1284704537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25983; }","duration":"137.417016ms","start":"2026-07-02T16:27:58.759905Z","end":"2026-07-02T16:27:58.897322Z","steps":["trace[1284704537] 'agreement among raft nodes before linearized reading' (duration: 137.252902ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:27:58.897325Z","caller":"traceutil/trace.go:171","msg":"trace[521807268] transaction","detail":"{read_only:false; response_revision:25981; number_of_response:1; }","duration":"491.228551ms","start":"2026-07-02T16:27:58.406035Z","end":"2026-07-02T16:27:58.897264Z","steps":["trace[521807268] 'process raft request' (duration: 353.909608ms)","trace[521807268] 'compare' (duration: 136.345113ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:27:58.897376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:27:58.53724Z","time spent":"360.048222ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:27:58.897565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:27:58.405999Z","time spent":"491.438097ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:27:58.897542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:27:58.476212Z","time spent":"421.064028ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:27:58.897728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.395123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T16:27:58.897805Z","caller":"traceutil/trace.go:171","msg":"trace[378444888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25983; }","duration":"141.522406ms","start":"2026-07-02T16:27:58.756264Z","end":"2026-07-02T16:27:58.897786Z","steps":["trace[378444888] 'agreement among raft nodes before linearized reading' (duration: 140.623035ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:27:59.413659Z","caller":"traceutil/trace.go:171","msg":"trace[1199968662] transaction","detail":"{read_only:false; response_revision:25987; number_of_response:1; }","duration":"273.285037ms","start":"2026-07-02T16:27:59.140345Z","end":"2026-07-02T16:27:59.41363Z","steps":["trace[1199968662] 'process raft request' (duration: 273.081843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:27:59.650961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.004899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:27:59.651166Z","caller":"traceutil/trace.go:171","msg":"trace[343322304] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"364.925989ms","start":"2026-07-02T16:27:59.286219Z","end":"2026-07-02T16:27:59.651145Z","steps":["trace[343322304] 'process raft request' (duration: 213.627212ms)","trace[343322304] 'compare' (duration: 150.842606ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:27:59.651349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:27:59.286192Z","time spent":"365.101703ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:27:59.651524Z","caller":"traceutil/trace.go:171","msg":"trace[2055994182] transaction","detail":"{read_only:false; response_revision:25989; number_of_response:1; }","duration":"260.764455ms","start":"2026-07-02T16:27:59.390734Z","end":"2026-07-02T16:27:59.651499Z","steps":["trace[2055994182] 'process raft request' (duration: 260.354717ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:27:59.651852Z","caller":"traceutil/trace.go:171","msg":"trace[923576746] linearizableReadLoop","detail":"{readStateIndex:26841; appliedIndex:26839; }","duration":"353.384687ms","start":"2026-07-02T16:27:59.29844Z","end":"2026-07-02T16:27:59.651825Z","steps":["trace[923576746] 'read index received' (duration: 115.08948ms)","trace[923576746] 'applied index is now lower than readState.Index' (duration: 238.294217ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:27:59.651888Z","caller":"traceutil/trace.go:171","msg":"trace[175288720] transaction","detail":"{read_only:false; response_revision:25991; number_of_response:1; }","duration":"255.811667ms","start":"2026-07-02T16:27:59.396046Z","end":"2026-07-02T16:27:59.651858Z","steps":["trace[175288720] 'process raft request' (duration: 255.653724ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:27:59.651851Z","caller":"traceutil/trace.go:171","msg":"trace[1513784872] transaction","detail":"{read_only:false; response_revision:25990; number_of_response:1; }","duration":"256.456502ms","start":"2026-07-02T16:27:59.395352Z","end":"2026-07-02T16:27:59.651808Z","steps":["trace[1513784872] 'process raft request' (duration: 256.034953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:27:59.65208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.619082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T16:27:59.652134Z","caller":"traceutil/trace.go:171","msg":"trace[1906012915] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25991; }","duration":"353.720195ms","start":"2026-07-02T16:27:59.298399Z","end":"2026-07-02T16:27:59.652119Z","steps":["trace[1906012915] 'agreement among raft nodes before linearized reading' (duration: 353.581882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:27:59.652191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:27:59.298383Z","time spent":"353.794446ms","remote":"127.0.0.1:39818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-07-02T16:27:59.983972Z","caller":"traceutil/trace.go:171","msg":"trace[1643997665] transaction","detail":"{read_only:false; response_revision:25992; number_of_response:1; }","duration":"326.066163ms","start":"2026-07-02T16:27:59.657878Z","end":"2026-07-02T16:27:59.983945Z","steps":["trace[1643997665] 'process raft request' (duration: 325.88947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:27:59.98415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:27:59.657842Z","time spent":"326.206367ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:28:00.010579Z","caller":"traceutil/trace.go:171","msg":"trace[31719478] transaction","detail":"{read_only:false; response_revision:25993; number_of_response:1; }","duration":"134.636005ms","start":"2026-07-02T16:27:59.875915Z","end":"2026-07-02T16:28:00.010551Z","steps":["trace[31719478] 'process raft request' (duration: 134.438261ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:00.010876Z","caller":"traceutil/trace.go:171","msg":"trace[2005591990] transaction","detail":"{read_only:false; response_revision:25994; number_of_response:1; }","duration":"124.581717ms","start":"2026-07-02T16:27:59.88622Z","end":"2026-07-02T16:28:00.010801Z","steps":["trace[2005591990] 'process raft request' (duration: 124.286311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:00.503269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.805062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:00.503339Z","caller":"traceutil/trace.go:171","msg":"trace[617342501] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25995; }","duration":"153.922185ms","start":"2026-07-02T16:28:00.349402Z","end":"2026-07-02T16:28:00.503324Z","steps":["trace[617342501] 'count revisions from in-memory index tree' (duration: 153.7302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:00.503533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.628558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:28:00.503639Z","caller":"traceutil/trace.go:171","msg":"trace[1679793134] transaction","detail":"{read_only:false; response_revision:25996; number_of_response:1; }","duration":"153.128347ms","start":"2026-07-02T16:28:00.35049Z","end":"2026-07-02T16:28:00.503618Z","steps":["trace[1679793134] 'process raft request' (duration: 16.280084ms)","trace[1679793134] 'compare' (duration: 136.378353ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:00.792976Z","caller":"traceutil/trace.go:171","msg":"trace[1508420899] transaction","detail":"{read_only:false; response_revision:25997; number_of_response:1; }","duration":"349.658497ms","start":"2026-07-02T16:28:00.443287Z","end":"2026-07-02T16:28:00.792946Z","steps":["trace[1508420899] 'process raft request' (duration: 314.873181ms)","trace[1508420899] 'compare' (duration: 34.54863ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:00.793016Z","caller":"traceutil/trace.go:171","msg":"trace[1491220863] transaction","detail":"{read_only:false; response_revision:25998; number_of_response:1; }","duration":"349.287339ms","start":"2026-07-02T16:28:00.443695Z","end":"2026-07-02T16:28:00.792982Z","steps":["trace[1491220863] 'process raft request' (duration: 349.199877ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:00.793038Z","caller":"traceutil/trace.go:171","msg":"trace[643000003] linearizableReadLoop","detail":"{readStateIndex:26851; appliedIndex:26849; }","duration":"159.846943ms","start":"2026-07-02T16:28:00.633128Z","end":"2026-07-02T16:28:00.792975Z","steps":["trace[643000003] 'read index received' (duration: 125.19079ms)","trace[643000003] 'applied index is now lower than readState.Index' (duration: 34.650123ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:28:00.793133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:00.443254Z","time spent":"349.79037ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:28:00.793196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:00.443646Z","time spent":"349.470713ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:28:00.79318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.031197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:00.793268Z","caller":"traceutil/trace.go:171","msg":"trace[777106019] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25998; }","duration":"160.133229ms","start":"2026-07-02T16:28:00.633116Z","end":"2026-07-02T16:28:00.793249Z","steps":["trace[777106019] 'agreement among raft nodes before linearized reading' (duration: 159.930005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:01.040567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.050712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:28:01.040753Z","caller":"traceutil/trace.go:171","msg":"trace[1055026578] transaction","detail":"{read_only:false; response_revision:26000; number_of_response:1; }","duration":"137.706313ms","start":"2026-07-02T16:28:00.90303Z","end":"2026-07-02T16:28:01.040736Z","steps":["trace[1055026578] 'process raft request' (duration: 137.655011ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:01.040775Z","caller":"traceutil/trace.go:171","msg":"trace[944388106] linearizableReadLoop","detail":"{readStateIndex:26852; appliedIndex:26851; }","duration":"214.890809ms","start":"2026-07-02T16:28:00.825872Z","end":"2026-07-02T16:28:01.040763Z","steps":["trace[944388106] 'read index received' (duration: 75.417309ms)","trace[944388106] 'applied index is now lower than readState.Index' (duration: 139.440529ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:01.040897Z","caller":"traceutil/trace.go:171","msg":"trace[843435280] transaction","detail":"{read_only:false; response_revision:25999; number_of_response:1; }","duration":"218.972219ms","start":"2026-07-02T16:28:00.821907Z","end":"2026-07-02T16:28:01.040879Z","steps":["trace[843435280] 'process raft request' (duration: 79.513559ms)","trace[843435280] 'compare' (duration: 138.909907ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:28:01.041222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.348669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-02T16:28:01.041246Z","caller":"traceutil/trace.go:171","msg":"trace[2134992746] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26000; }","duration":"215.41415ms","start":"2026-07-02T16:28:00.825825Z","end":"2026-07-02T16:28:01.041239Z","steps":["trace[2134992746] 'agreement among raft nodes before linearized reading' (duration: 215.203096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:01.041248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.198367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T16:28:01.042204Z","caller":"traceutil/trace.go:171","msg":"trace[417755454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26000; }","duration":"112.207108ms","start":"2026-07-02T16:28:00.929978Z","end":"2026-07-02T16:28:01.042186Z","steps":["trace[417755454] 'agreement among raft nodes before linearized reading' (duration: 111.118875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:01.341119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.998522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:01.341222Z","caller":"traceutil/trace.go:171","msg":"trace[152924097] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26003; }","duration":"140.189926ms","start":"2026-07-02T16:28:01.201007Z","end":"2026-07-02T16:28:01.341197Z","steps":["trace[152924097] 'range keys from in-memory index tree' (duration: 139.774467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:01.341215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.916141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:01.341294Z","caller":"traceutil/trace.go:171","msg":"trace[1208124546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26003; }","duration":"129.048123ms","start":"2026-07-02T16:28:01.212224Z","end":"2026-07-02T16:28:01.341272Z","steps":["trace[1208124546] 'range keys from in-memory index tree' (duration: 128.789838ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:01.794818Z","caller":"traceutil/trace.go:171","msg":"trace[812231137] transaction","detail":"{read_only:false; response_revision:26005; number_of_response:1; }","duration":"137.283372ms","start":"2026-07-02T16:28:01.657496Z","end":"2026-07-02T16:28:01.794779Z","steps":["trace[812231137] 'process raft request' (duration: 126.009497ms)","trace[812231137] 'compare' (duration: 10.867837ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:01.796007Z","caller":"traceutil/trace.go:171","msg":"trace[1537280525] transaction","detail":"{read_only:false; response_revision:26007; number_of_response:1; }","duration":"137.261162ms","start":"2026-07-02T16:28:01.658712Z","end":"2026-07-02T16:28:01.795973Z","steps":["trace[1537280525] 'process raft request' (duration: 137.096238ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:01.796067Z","caller":"traceutil/trace.go:171","msg":"trace[1107135055] transaction","detail":"{read_only:false; response_revision:26006; number_of_response:1; }","duration":"137.62571ms","start":"2026-07-02T16:28:01.658387Z","end":"2026-07-02T16:28:01.796012Z","steps":["trace[1107135055] 'process raft request' (duration: 137.225961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:02.223604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.004617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:02.223673Z","caller":"traceutil/trace.go:171","msg":"trace[176121836] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:26010; }","duration":"120.115619ms","start":"2026-07-02T16:28:02.103541Z","end":"2026-07-02T16:28:02.223656Z","steps":["trace[176121836] 'count revisions from in-memory index tree' (duration: 119.861714ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:02.350213Z","caller":"traceutil/trace.go:171","msg":"trace[1915459005] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"125.252661ms","start":"2026-07-02T16:28:02.22493Z","end":"2026-07-02T16:28:02.350182Z","steps":["trace[1915459005] 'process raft request' (duration: 125.087268ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:03.266204Z","caller":"traceutil/trace.go:171","msg":"trace[62114976] linearizableReadLoop","detail":"{readStateIndex:26867; appliedIndex:26866; }","duration":"179.618962ms","start":"2026-07-02T16:28:03.086558Z","end":"2026-07-02T16:28:03.266177Z","steps":["trace[62114976] 'read index received' (duration: 179.350116ms)","trace[62114976] 'applied index is now lower than readState.Index' (duration: 267.776µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:03.26633Z","caller":"traceutil/trace.go:171","msg":"trace[226810460] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"219.673542ms","start":"2026-07-02T16:28:03.046615Z","end":"2026-07-02T16:28:03.266289Z","steps":["trace[226810460] 'process raft request' (duration: 219.374006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:03.266506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.937339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T16:28:03.266547Z","caller":"traceutil/trace.go:171","msg":"trace[826308334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26013; }","duration":"180.0337ms","start":"2026-07-02T16:28:03.086501Z","end":"2026-07-02T16:28:03.266534Z","steps":["trace[826308334] 'agreement among raft nodes before linearized reading' (duration: 179.838636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:03.266674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.106771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T16:28:03.266735Z","caller":"traceutil/trace.go:171","msg":"trace[19506315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26013; }","duration":"127.201413ms","start":"2026-07-02T16:28:03.139519Z","end":"2026-07-02T16:28:03.26672Z","steps":["trace[19506315] 'agreement among raft nodes before linearized reading' (duration: 127.04754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:03.26675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.254904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T16:28:03.266845Z","caller":"traceutil/trace.go:171","msg":"trace[591257098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26013; }","duration":"127.395647ms","start":"2026-07-02T16:28:03.139423Z","end":"2026-07-02T16:28:03.266819Z","steps":["trace[591257098] 'agreement among raft nodes before linearized reading' (duration: 127.096031ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:04.112972Z","caller":"traceutil/trace.go:171","msg":"trace[194314917] linearizableReadLoop","detail":"{readStateIndex:26874; appliedIndex:26872; }","duration":"205.76141ms","start":"2026-07-02T16:28:03.907183Z","end":"2026-07-02T16:28:04.112945Z","steps":["trace[194314917] 'read index received' (duration: 205.530965ms)","trace[194314917] 'applied index is now lower than readState.Index' (duration: 228.975µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:04.11336Z","caller":"traceutil/trace.go:171","msg":"trace[1420996272] transaction","detail":"{read_only:false; response_revision:26019; number_of_response:1; }","duration":"312.017468ms","start":"2026-07-02T16:28:03.80133Z","end":"2026-07-02T16:28:04.113347Z","steps":["trace[1420996272] 'process raft request' (duration: 311.396885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:04.113475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:03.801307Z","time spent":"312.09207ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:28:04.113645Z","caller":"traceutil/trace.go:171","msg":"trace[1550043445] transaction","detail":"{read_only:false; response_revision:26020; number_of_response:1; }","duration":"311.766884ms","start":"2026-07-02T16:28:03.801847Z","end":"2026-07-02T16:28:04.113614Z","steps":["trace[1550043445] 'process raft request' (duration: 311.049328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:04.113707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:03.801839Z","time spent":"311.837476ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:28:04.113832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.64736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:04.113872Z","caller":"traceutil/trace.go:171","msg":"trace[1936811680] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:26020; }","duration":"206.747531ms","start":"2026-07-02T16:28:03.907112Z","end":"2026-07-02T16:28:04.11386Z","steps":["trace[1936811680] 'agreement among raft nodes before linearized reading' (duration: 206.67347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:04.414143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.475162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:28:04.414974Z","caller":"traceutil/trace.go:171","msg":"trace[602902505] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"345.451924ms","start":"2026-07-02T16:28:04.069483Z","end":"2026-07-02T16:28:04.414935Z","steps":["trace[602902505] 'process raft request' (duration: 152.085293ms)","trace[602902505] 'compare' (duration: 192.085844ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:04.414982Z","caller":"traceutil/trace.go:171","msg":"trace[1968000429] linearizableReadLoop","detail":"{readStateIndex:26876; appliedIndex:26874; }","duration":"301.91484ms","start":"2026-07-02T16:28:04.113048Z","end":"2026-07-02T16:28:04.414963Z","steps":["trace[1968000429] 'read index received' (duration: 108.481177ms)","trace[1968000429] 'applied index is now lower than readState.Index' (duration: 193.432733ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:04.415086Z","caller":"traceutil/trace.go:171","msg":"trace[1218814011] transaction","detail":"{read_only:false; response_revision:26022; number_of_response:1; }","duration":"344.567876ms","start":"2026-07-02T16:28:04.070483Z","end":"2026-07-02T16:28:04.415051Z","steps":["trace[1218814011] 'process raft request' (duration: 344.393202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:04.415119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:04.069463Z","time spent":"345.580968ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:28:04.415248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:04.070473Z","time spent":"344.687508ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:28:04.415491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.249464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-02T16:28:04.415639Z","caller":"traceutil/trace.go:171","msg":"trace[921637538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26022; }","duration":"359.394468ms","start":"2026-07-02T16:28:04.056169Z","end":"2026-07-02T16:28:04.415563Z","steps":["trace[921637538] 'agreement among raft nodes before linearized reading' (duration: 358.948998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:04.416783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:04.056152Z","time spent":"360.612804ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T16:28:04.425591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.558658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:04.425652Z","caller":"traceutil/trace.go:171","msg":"trace[1236134436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26023; }","duration":"212.66121ms","start":"2026-07-02T16:28:04.212974Z","end":"2026-07-02T16:28:04.425636Z","steps":["trace[1236134436] 'agreement among raft nodes before linearized reading' (duration: 212.543457ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:04.586947Z","caller":"traceutil/trace.go:171","msg":"trace[875184396] linearizableReadLoop","detail":"{readStateIndex:26878; appliedIndex:26877; }","duration":"160.060348ms","start":"2026-07-02T16:28:04.426868Z","end":"2026-07-02T16:28:04.586928Z","steps":["trace[875184396] 'read index received' (duration: 159.876664ms)","trace[875184396] 'applied index is now lower than readState.Index' (duration: 183.063µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:28:04.587095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.209682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:04.58705Z","caller":"traceutil/trace.go:171","msg":"trace[250698156] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"166.321874ms","start":"2026-07-02T16:28:04.420694Z","end":"2026-07-02T16:28:04.587016Z","steps":["trace[250698156] 'process raft request' (duration: 166.106909ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:04.587128Z","caller":"traceutil/trace.go:171","msg":"trace[875110396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26024; }","duration":"160.290924ms","start":"2026-07-02T16:28:04.426829Z","end":"2026-07-02T16:28:04.58712Z","steps":["trace[875110396] 'agreement among raft nodes before linearized reading' (duration: 160.196172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:04.58719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.378437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-02T16:28:04.587251Z","caller":"traceutil/trace.go:171","msg":"trace[219436527] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26024; }","duration":"138.481279ms","start":"2026-07-02T16:28:04.448757Z","end":"2026-07-02T16:28:04.587238Z","steps":["trace[219436527] 'agreement among raft nodes before linearized reading' (duration: 138.351976ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:11.106361Z","caller":"traceutil/trace.go:171","msg":"trace[1006663907] transaction","detail":"{read_only:false; response_revision:26069; number_of_response:1; }","duration":"266.613502ms","start":"2026-07-02T16:28:10.839719Z","end":"2026-07-02T16:28:11.106333Z","steps":["trace[1006663907] 'process raft request' (duration: 266.454949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:11.669784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.724816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:11.669872Z","caller":"traceutil/trace.go:171","msg":"trace[927436087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26069; }","duration":"458.874839ms","start":"2026-07-02T16:28:11.210979Z","end":"2026-07-02T16:28:11.669854Z","steps":["trace[927436087] 'range keys from in-memory index tree' (duration: 458.596864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:11.669915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:11.210953Z","time spent":"458.951641ms","remote":"127.0.0.1:39610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T16:28:11.670168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.970607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:28:11.670339Z","caller":"traceutil/trace.go:171","msg":"trace[2061331732] transaction","detail":"{read_only:false; response_revision:26070; number_of_response:1; }","duration":"366.664686ms","start":"2026-07-02T16:28:11.303659Z","end":"2026-07-02T16:28:11.670323Z","steps":["trace[2061331732] 'process raft request' (duration: 95.392823ms)","trace[2061331732] 'compare' (duration: 270.67761ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:28:11.670463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:11.303622Z","time spent":"366.766129ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:28:11.67049Z","caller":"traceutil/trace.go:171","msg":"trace[1760107304] transaction","detail":"{read_only:false; response_revision:26071; number_of_response:1; }","duration":"263.414962ms","start":"2026-07-02T16:28:11.407009Z","end":"2026-07-02T16:28:11.670424Z","steps":["trace[1760107304] 'process raft request' (duration: 263.283019ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:11.670506Z","caller":"traceutil/trace.go:171","msg":"trace[1188323036] linearizableReadLoop","detail":"{readStateIndex:26925; appliedIndex:26924; }","duration":"325.016051ms","start":"2026-07-02T16:28:11.345453Z","end":"2026-07-02T16:28:11.670469Z","steps":["trace[1188323036] 'read index received' (duration: 53.632505ms)","trace[1188323036] 'applied index is now lower than readState.Index' (duration: 271.380996ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:28:11.670885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.41841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T16:28:11.670946Z","caller":"traceutil/trace.go:171","msg":"trace[403513605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26071; }","duration":"325.528002ms","start":"2026-07-02T16:28:11.345398Z","end":"2026-07-02T16:28:11.670926Z","steps":["trace[403513605] 'agreement among raft nodes before linearized reading' (duration: 325.286127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:11.670993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:11.345377Z","time spent":"325.603753ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T16:28:11.671099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.24553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:11.671162Z","caller":"traceutil/trace.go:171","msg":"trace[1159756813] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:26071; }","duration":"125.358983ms","start":"2026-07-02T16:28:11.54578Z","end":"2026-07-02T16:28:11.671139Z","steps":["trace[1159756813] 'agreement among raft nodes before linearized reading' (duration: 125.24948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:11.671149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.226475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-02T16:28:11.67121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.432642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-02T16:28:11.671428Z","caller":"traceutil/trace.go:171","msg":"trace[398749222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26071; }","duration":"325.367398ms","start":"2026-07-02T16:28:11.345859Z","end":"2026-07-02T16:28:11.671226Z","steps":["trace[398749222] 'agreement among raft nodes before linearized reading' (duration: 325.081472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:11.671446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.161701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-02T16:28:11.671477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:11.345843Z","time spent":"325.624934ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T16:28:11.671477Z","caller":"traceutil/trace.go:171","msg":"trace[1036386221] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26071; }","duration":"193.760949ms","start":"2026-07-02T16:28:11.477694Z","end":"2026-07-02T16:28:11.671455Z","steps":["trace[1036386221] 'agreement among raft nodes before linearized reading' (duration: 193.291369ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:11.671492Z","caller":"traceutil/trace.go:171","msg":"trace[367891918] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26071; }","duration":"236.267253ms","start":"2026-07-02T16:28:11.435211Z","end":"2026-07-02T16:28:11.671479Z","steps":["trace[367891918] 'agreement among raft nodes before linearized reading' (duration: 236.008497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:11.671457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.907655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T16:28:11.671562Z","caller":"traceutil/trace.go:171","msg":"trace[945218658] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:26071; }","duration":"253.249512ms","start":"2026-07-02T16:28:11.418285Z","end":"2026-07-02T16:28:11.671534Z","steps":["trace[945218658] 'agreement among raft nodes before linearized reading' (duration: 252.794222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:11.671691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.683656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T16:28:11.671738Z","caller":"traceutil/trace.go:171","msg":"trace[1516839264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26071; }","duration":"325.727077ms","start":"2026-07-02T16:28:11.345999Z","end":"2026-07-02T16:28:11.671726Z","steps":["trace[1516839264] 'agreement among raft nodes before linearized reading' (duration: 325.587753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:11.671766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:11.345957Z","time spent":"325.802769ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T16:28:12.033295Z","caller":"traceutil/trace.go:171","msg":"trace[1074635148] transaction","detail":"{read_only:false; response_revision:26076; number_of_response:1; }","duration":"164.355361ms","start":"2026-07-02T16:28:11.868926Z","end":"2026-07-02T16:28:12.033282Z","steps":["trace[1074635148] 'process raft request' (duration: 164.30947ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:12.03335Z","caller":"traceutil/trace.go:171","msg":"trace[1588534421] linearizableReadLoop","detail":"{readStateIndex:26930; appliedIndex:26926; }","duration":"355.483933ms","start":"2026-07-02T16:28:11.677832Z","end":"2026-07-02T16:28:12.033316Z","steps":["trace[1588534421] 'read index received' (duration: 4.17544ms)","trace[1588534421] 'applied index is now lower than readState.Index' (duration: 351.305183ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:12.033478Z","caller":"traceutil/trace.go:171","msg":"trace[2107455630] transaction","detail":"{read_only:false; response_revision:26073; number_of_response:1; }","duration":"356.305152ms","start":"2026-07-02T16:28:11.676961Z","end":"2026-07-02T16:28:12.033266Z","steps":["trace[2107455630] 'process raft request' (duration: 322.265891ms)","trace[2107455630] 'compare' (duration: 33.679042ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:12.033359Z","caller":"traceutil/trace.go:171","msg":"trace[1789917974] transaction","detail":"{read_only:false; response_revision:26075; number_of_response:1; }","duration":"356.294502ms","start":"2026-07-02T16:28:11.677015Z","end":"2026-07-02T16:28:12.033309Z","steps":["trace[1789917974] 'process raft request' (duration: 356.18825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:12.03382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:11.676987Z","time spent":"356.778402ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:28:12.033362Z","caller":"traceutil/trace.go:171","msg":"trace[2044800932] transaction","detail":"{read_only:false; response_revision:26074; number_of_response:1; }","duration":"356.314072ms","start":"2026-07-02T16:28:11.676992Z","end":"2026-07-02T16:28:12.033306Z","steps":["trace[2044800932] 'process raft request' (duration: 356.177509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:12.033969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:11.67697Z","time spent":"356.976426ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:28:12.034256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:11.676946Z","time spent":"356.622528ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:28:12.035256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.375373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18353"} {"level":"info","ts":"2026-07-02T16:28:12.035373Z","caller":"traceutil/trace.go:171","msg":"trace[2030220471] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26076; }","duration":"357.536327ms","start":"2026-07-02T16:28:11.677813Z","end":"2026-07-02T16:28:12.035349Z","steps":["trace[2030220471] 'agreement among raft nodes before linearized reading' (duration: 355.643147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:12.035431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:11.677805Z","time spent":"357.615489ms","remote":"127.0.0.1:39818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18375,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-02T16:28:12.302553Z","caller":"traceutil/trace.go:171","msg":"trace[1258844297] transaction","detail":"{read_only:false; response_revision:26077; number_of_response:1; }","duration":"150.341956ms","start":"2026-07-02T16:28:12.152176Z","end":"2026-07-02T16:28:12.302518Z","steps":["trace[1258844297] 'process raft request' (duration: 148.734821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:12.705438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.536648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:28:12.705689Z","caller":"traceutil/trace.go:171","msg":"trace[1078845487] transaction","detail":"{read_only:false; response_revision:26078; number_of_response:1; }","duration":"551.461461ms","start":"2026-07-02T16:28:12.154165Z","end":"2026-07-02T16:28:12.705626Z","steps":["trace[1078845487] 'process raft request' (duration: 314.638646ms)","trace[1078845487] 'compare' (duration: 236.102059ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:28:12.705937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:12.154154Z","time spent":"551.648625ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:28:12.70852Z","caller":"traceutil/trace.go:171","msg":"trace[599159403] transaction","detail":"{read_only:false; response_revision:26080; number_of_response:1; }","duration":"252.126427ms","start":"2026-07-02T16:28:12.456351Z","end":"2026-07-02T16:28:12.708478Z","steps":["trace[599159403] 'process raft request' (duration: 251.985414ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:12.708527Z","caller":"traceutil/trace.go:171","msg":"trace[1662526703] transaction","detail":"{read_only:false; response_revision:26081; number_of_response:1; }","duration":"251.973714ms","start":"2026-07-02T16:28:12.456504Z","end":"2026-07-02T16:28:12.708478Z","steps":["trace[1662526703] 'process raft request' (duration: 251.855922ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:12.708571Z","caller":"traceutil/trace.go:171","msg":"trace[1407702219] transaction","detail":"{read_only:false; response_revision:26079; number_of_response:1; }","duration":"252.64061ms","start":"2026-07-02T16:28:12.455905Z","end":"2026-07-02T16:28:12.708546Z","steps":["trace[1407702219] 'process raft request' (duration: 252.364814ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:17.33747Z","caller":"traceutil/trace.go:171","msg":"trace[10246400] linearizableReadLoop","detail":"{readStateIndex:27027; appliedIndex:27026; }","duration":"196.286794ms","start":"2026-07-02T16:28:17.141162Z","end":"2026-07-02T16:28:17.337448Z","steps":["trace[10246400] 'read index received' (duration: 196.148441ms)","trace[10246400] 'applied index is now lower than readState.Index' (duration: 137.563µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:17.337721Z","caller":"traceutil/trace.go:171","msg":"trace[1083307982] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"202.654022ms","start":"2026-07-02T16:28:17.135056Z","end":"2026-07-02T16:28:17.33771Z","steps":["trace[1083307982] 'process raft request' (duration: 202.183302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:17.338016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.158161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T16:28:17.33808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.892017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-02T16:28:17.338142Z","caller":"traceutil/trace.go:171","msg":"trace[1986726547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26170; }","duration":"126.339595ms","start":"2026-07-02T16:28:17.211776Z","end":"2026-07-02T16:28:17.338116Z","steps":["trace[1986726547] 'agreement among raft nodes before linearized reading' (duration: 126.1161ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:17.338159Z","caller":"traceutil/trace.go:171","msg":"trace[1139791062] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26170; }","duration":"197.099692ms","start":"2026-07-02T16:28:17.14104Z","end":"2026-07-02T16:28:17.338139Z","steps":["trace[1139791062] 'agreement among raft nodes before linearized reading' (duration: 196.841956ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:17.577714Z","caller":"traceutil/trace.go:171","msg":"trace[1389567181] linearizableReadLoop","detail":"{readStateIndex:27029; appliedIndex:27028; }","duration":"227.078664ms","start":"2026-07-02T16:28:17.350403Z","end":"2026-07-02T16:28:17.577482Z","steps":["trace[1389567181] 'read index received' (duration: 127.476329ms)","trace[1389567181] 'applied index is now lower than readState.Index' (duration: 99.599975ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:17.577928Z","caller":"traceutil/trace.go:171","msg":"trace[476557132] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"227.778099ms","start":"2026-07-02T16:28:17.350132Z","end":"2026-07-02T16:28:17.57791Z","steps":["trace[476557132] 'process raft request' (duration: 127.865908ms)","trace[476557132] 'compare' (duration: 99.230306ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:28:17.578172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.597831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18be85df25998b6c\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-07-02T16:28:17.578236Z","caller":"traceutil/trace.go:171","msg":"trace[200898590] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18be85df25998b6c; range_end:; response_count:1; response_revision:26171; }","duration":"132.727123ms","start":"2026-07-02T16:28:17.445492Z","end":"2026-07-02T16:28:17.578219Z","steps":["trace[200898590] 'agreement among raft nodes before linearized reading' (duration: 132.513019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:17.578281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.84482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18353"} {"level":"info","ts":"2026-07-02T16:28:17.578387Z","caller":"traceutil/trace.go:171","msg":"trace[1469749711] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26171; }","duration":"228.007063ms","start":"2026-07-02T16:28:17.350358Z","end":"2026-07-02T16:28:17.578365Z","steps":["trace[1469749711] 'agreement among raft nodes before linearized reading' (duration: 227.685897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:17.836333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.173297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:28:17.83698Z","caller":"traceutil/trace.go:171","msg":"trace[1245006075] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"141.433602ms","start":"2026-07-02T16:28:17.695476Z","end":"2026-07-02T16:28:17.83691Z","steps":["trace[1245006075] 'process raft request' (duration: 141.32598ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:17.836987Z","caller":"traceutil/trace.go:171","msg":"trace[712646412] transaction","detail":"{read_only:false; response_revision:26172; number_of_response:1; }","duration":"255.631834ms","start":"2026-07-02T16:28:17.581263Z","end":"2026-07-02T16:28:17.836895Z","steps":["trace[712646412] 'process raft request' (duration: 124.790782ms)","trace[712646412] 'compare' (duration: 130.034284ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:17.837133Z","caller":"traceutil/trace.go:171","msg":"trace[1896822771] linearizableReadLoop","detail":"{readStateIndex:27030; appliedIndex:27029; }","duration":"253.64878ms","start":"2026-07-02T16:28:17.583302Z","end":"2026-07-02T16:28:17.836912Z","steps":["trace[1896822771] 'read index received' (duration: 122.781848ms)","trace[1896822771] 'applied index is now lower than readState.Index' (duration: 130.824131ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:28:17.837259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.925386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-07-02T16:28:17.83737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.682514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:17.837423Z","caller":"traceutil/trace.go:171","msg":"trace[1315566852] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26173; }","duration":"185.811467ms","start":"2026-07-02T16:28:17.651599Z","end":"2026-07-02T16:28:17.83741Z","steps":["trace[1315566852] 'agreement among raft nodes before linearized reading' (duration: 185.685934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:17.837483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.900069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T16:28:17.837541Z","caller":"traceutil/trace.go:171","msg":"trace[128107119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26173; }","duration":"133.975721ms","start":"2026-07-02T16:28:17.703543Z","end":"2026-07-02T16:28:17.837518Z","steps":["trace[128107119] 'agreement among raft nodes before linearized reading' (duration: 133.831807ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:17.837857Z","caller":"traceutil/trace.go:171","msg":"trace[599731912] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:26173; }","duration":"254.566661ms","start":"2026-07-02T16:28:17.583277Z","end":"2026-07-02T16:28:17.837844Z","steps":["trace[599731912] 'agreement among raft nodes before linearized reading' (duration: 253.681971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:20.214119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.08671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:20.214213Z","caller":"traceutil/trace.go:171","msg":"trace[1092371578] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:26194; }","duration":"196.242033ms","start":"2026-07-02T16:28:20.017951Z","end":"2026-07-02T16:28:20.214193Z","steps":["trace[1092371578] 'count revisions from in-memory index tree' (duration: 195.989369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:20.214194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.932261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0def9c2f-49f4-4b20-af69-0d48ec1dcf54\" ","response":"range_response_count:1 size:3947"} {"level":"info","ts":"2026-07-02T16:28:20.214304Z","caller":"traceutil/trace.go:171","msg":"trace[1279926021] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0def9c2f-49f4-4b20-af69-0d48ec1dcf54; range_end:; response_count:1; response_revision:26194; }","duration":"233.120164ms","start":"2026-07-02T16:28:19.981159Z","end":"2026-07-02T16:28:20.214279Z","steps":["trace[1279926021] 'range keys from in-memory index tree' (duration: 232.688685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:20.215063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.877746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:28:20.215135Z","caller":"traceutil/trace.go:171","msg":"trace[1491339051] linearizableReadLoop","detail":"{readStateIndex:27053; appliedIndex:27052; }","duration":"145.688015ms","start":"2026-07-02T16:28:20.069436Z","end":"2026-07-02T16:28:20.215124Z","steps":["trace[1491339051] 'read index received' (duration: 6.632605ms)","trace[1491339051] 'applied index is now lower than readState.Index' (duration: 139.05425ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:20.215279Z","caller":"traceutil/trace.go:171","msg":"trace[1421682468] transaction","detail":"{read_only:false; response_revision:26195; number_of_response:1; }","duration":"146.794619ms","start":"2026-07-02T16:28:20.068451Z","end":"2026-07-02T16:28:20.215245Z","steps":["trace[1421682468] 'compare' (duration: 137.852265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:20.215372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.940231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T16:28:20.215418Z","caller":"traceutil/trace.go:171","msg":"trace[430989996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26195; }","duration":"146.014592ms","start":"2026-07-02T16:28:20.069393Z","end":"2026-07-02T16:28:20.215407Z","steps":["trace[430989996] 'agreement among raft nodes before linearized reading' (duration: 145.842339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:20.215418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.188181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-02T16:28:20.215423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.131629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-02T16:28:20.215478Z","caller":"traceutil/trace.go:171","msg":"trace[175998804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26195; }","duration":"143.307913ms","start":"2026-07-02T16:28:20.072153Z","end":"2026-07-02T16:28:20.215461Z","steps":["trace[175998804] 'agreement among raft nodes before linearized reading' (duration: 143.107929ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:20.215481Z","caller":"traceutil/trace.go:171","msg":"trace[259794626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26195; }","duration":"143.241372ms","start":"2026-07-02T16:28:20.072227Z","end":"2026-07-02T16:28:20.215468Z","steps":["trace[259794626] 'agreement among raft nodes before linearized reading' (duration: 143.037377ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:20.387084Z","caller":"traceutil/trace.go:171","msg":"trace[800945406] linearizableReadLoop","detail":"{readStateIndex:27056; appliedIndex:27053; }","duration":"141.600117ms","start":"2026-07-02T16:28:20.245457Z","end":"2026-07-02T16:28:20.387057Z","steps":["trace[800945406] 'read index received' (duration: 19.220538ms)","trace[800945406] 'applied index is now lower than readState.Index' (duration: 122.378639ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:20.387107Z","caller":"traceutil/trace.go:171","msg":"trace[1414075651] transaction","detail":"{read_only:false; response_revision:26197; number_of_response:1; }","duration":"167.541631ms","start":"2026-07-02T16:28:20.219533Z","end":"2026-07-02T16:28:20.387075Z","steps":["trace[1414075651] 'process raft request' (duration: 167.295565ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:20.387125Z","caller":"traceutil/trace.go:171","msg":"trace[1688645844] transaction","detail":"{read_only:false; response_revision:26198; number_of_response:1; }","duration":"166.356435ms","start":"2026-07-02T16:28:20.220734Z","end":"2026-07-02T16:28:20.38709Z","steps":["trace[1688645844] 'process raft request' (duration: 166.266353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:20.387261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.797331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T16:28:20.387322Z","caller":"traceutil/trace.go:171","msg":"trace[1396632762] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:26198; }","duration":"141.912004ms","start":"2026-07-02T16:28:20.245399Z","end":"2026-07-02T16:28:20.387311Z","steps":["trace[1396632762] 'agreement among raft nodes before linearized reading' (duration: 141.76028ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:24.541275Z","caller":"traceutil/trace.go:171","msg":"trace[2104412596] linearizableReadLoop","detail":"{readStateIndex:27108; appliedIndex:27107; }","duration":"168.302377ms","start":"2026-07-02T16:28:24.37295Z","end":"2026-07-02T16:28:24.541253Z","steps":["trace[2104412596] 'read index received' (duration: 168.167074ms)","trace[2104412596] 'applied index is now lower than readState.Index' (duration: 134.613µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:24.541346Z","caller":"traceutil/trace.go:171","msg":"trace[1544317905] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"260.083941ms","start":"2026-07-02T16:28:24.2812Z","end":"2026-07-02T16:28:24.541284Z","steps":["trace[1544317905] 'process raft request' (duration: 259.921877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:24.541401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.42931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:24.541427Z","caller":"traceutil/trace.go:171","msg":"trace[859465421] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26248; }","duration":"168.502642ms","start":"2026-07-02T16:28:24.372916Z","end":"2026-07-02T16:28:24.541419Z","steps":["trace[859465421] 'agreement among raft nodes before linearized reading' (duration: 168.435731ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:24.56759Z","caller":"traceutil/trace.go:171","msg":"trace[1322287189] transaction","detail":"{read_only:false; response_revision:26249; number_of_response:1; }","duration":"168.364267ms","start":"2026-07-02T16:28:24.399188Z","end":"2026-07-02T16:28:24.567552Z","steps":["trace[1322287189] 'process raft request' (duration: 166.918125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:24.567636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.233934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-02T16:28:24.567678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.999757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T16:28:24.567697Z","caller":"traceutil/trace.go:171","msg":"trace[1890663199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26249; }","duration":"167.328175ms","start":"2026-07-02T16:28:24.400353Z","end":"2026-07-02T16:28:24.567682Z","steps":["trace[1890663199] 'agreement among raft nodes before linearized reading' (duration: 167.142711ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:24.567729Z","caller":"traceutil/trace.go:171","msg":"trace[667590146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26249; }","duration":"166.097378ms","start":"2026-07-02T16:28:24.40162Z","end":"2026-07-02T16:28:24.567717Z","steps":["trace[667590146] 'agreement among raft nodes before linearized reading' (duration: 165.947375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:24.905531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.379599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:28:24.905631Z","caller":"traceutil/trace.go:171","msg":"trace[551794453] linearizableReadLoop","detail":"{readStateIndex:27111; appliedIndex:27110; }","duration":"264.935476ms","start":"2026-07-02T16:28:24.640679Z","end":"2026-07-02T16:28:24.905615Z","steps":["trace[551794453] 'read index received' (duration: 125.341523ms)","trace[551794453] 'applied index is now lower than readState.Index' (duration: 139.592793ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:24.905669Z","caller":"traceutil/trace.go:171","msg":"trace[1015655056] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"332.829191ms","start":"2026-07-02T16:28:24.572799Z","end":"2026-07-02T16:28:24.905628Z","steps":["trace[1015655056] 'process raft request' (duration: 193.273069ms)","trace[1015655056] 'compare' (duration: 139.268966ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:28:24.905731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.063569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T16:28:24.905768Z","caller":"traceutil/trace.go:171","msg":"trace[1178913095] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:26251; }","duration":"265.147121ms","start":"2026-07-02T16:28:24.640611Z","end":"2026-07-02T16:28:24.905758Z","steps":["trace[1178913095] 'agreement among raft nodes before linearized reading' (duration: 265.07269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:24.905807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:28:24.572786Z","time spent":"332.942704ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:28:24.965479Z","caller":"traceutil/trace.go:171","msg":"trace[976301322] transaction","detail":"{read_only:false; response_revision:26252; number_of_response:1; }","duration":"206.036157ms","start":"2026-07-02T16:28:24.759408Z","end":"2026-07-02T16:28:24.965444Z","steps":["trace[976301322] 'process raft request' (duration: 205.770701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:24.965718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.986489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-02T16:28:24.965769Z","caller":"traceutil/trace.go:171","msg":"trace[2032259045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26255; }","duration":"197.080451ms","start":"2026-07-02T16:28:24.768674Z","end":"2026-07-02T16:28:24.965754Z","steps":["trace[2032259045] 'agreement among raft nodes before linearized reading' (duration: 196.841666ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:24.965862Z","caller":"traceutil/trace.go:171","msg":"trace[426709124] transaction","detail":"{read_only:false; response_revision:26253; number_of_response:1; }","duration":"206.166948ms","start":"2026-07-02T16:28:24.759683Z","end":"2026-07-02T16:28:24.96585Z","steps":["trace[426709124] 'process raft request' (duration: 205.684688ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:24.965967Z","caller":"traceutil/trace.go:171","msg":"trace[2036852060] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"206.2238ms","start":"2026-07-02T16:28:24.759729Z","end":"2026-07-02T16:28:24.965953Z","steps":["trace[2036852060] 'process raft request' (duration: 205.705288ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:24.966108Z","caller":"traceutil/trace.go:171","msg":"trace[2076968512] transaction","detail":"{read_only:false; response_revision:26255; number_of_response:1; }","duration":"206.316822ms","start":"2026-07-02T16:28:24.759779Z","end":"2026-07-02T16:28:24.966095Z","steps":["trace[2076968512] 'process raft request' (duration: 205.692758ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:27.247712Z","caller":"traceutil/trace.go:171","msg":"trace[467731879] linearizableReadLoop","detail":"{readStateIndex:27134; appliedIndex:27133; }","duration":"221.39773ms","start":"2026-07-02T16:28:27.026293Z","end":"2026-07-02T16:28:27.247691Z","steps":["trace[467731879] 'read index received' (duration: 139.703004ms)","trace[467731879] 'applied index is now lower than readState.Index' (duration: 81.693926ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:27.247886Z","caller":"traceutil/trace.go:171","msg":"trace[1915924481] transaction","detail":"{read_only:false; response_revision:26274; number_of_response:1; }","duration":"230.612041ms","start":"2026-07-02T16:28:27.0172Z","end":"2026-07-02T16:28:27.247812Z","steps":["trace[1915924481] 'process raft request' (duration: 148.783663ms)","trace[1915924481] 'compare' (duration: 81.604034ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:28:27.247969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.665355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10649"} {"level":"info","ts":"2026-07-02T16:28:27.247999Z","caller":"traceutil/trace.go:171","msg":"trace[544415637] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26274; }","duration":"221.738047ms","start":"2026-07-02T16:28:27.026253Z","end":"2026-07-02T16:28:27.247991Z","steps":["trace[544415637] 'agreement among raft nodes before linearized reading' (duration: 221.558053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:27.248048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.049416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-02T16:28:27.248186Z","caller":"traceutil/trace.go:171","msg":"trace[247614726] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26274; }","duration":"182.223509ms","start":"2026-07-02T16:28:27.065934Z","end":"2026-07-02T16:28:27.248157Z","steps":["trace[247614726] 'agreement among raft nodes before linearized reading' (duration: 181.914702ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:27.401911Z","caller":"traceutil/trace.go:171","msg":"trace[1536893238] transaction","detail":"{read_only:false; response_revision:26275; number_of_response:1; }","duration":"143.412496ms","start":"2026-07-02T16:28:27.258476Z","end":"2026-07-02T16:28:27.401888Z","steps":["trace[1536893238] 'process raft request' (duration: 142.504615ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:28.709483Z","caller":"traceutil/trace.go:171","msg":"trace[853224559] transaction","detail":"{read_only:false; response_revision:26282; number_of_response:1; }","duration":"122.318087ms","start":"2026-07-02T16:28:28.587127Z","end":"2026-07-02T16:28:28.709445Z","steps":["trace[853224559] 'process raft request' (duration: 122.125512ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:30.887764Z","caller":"traceutil/trace.go:171","msg":"trace[426103361] transaction","detail":"{read_only:false; response_revision:26299; number_of_response:1; }","duration":"171.081407ms","start":"2026-07-02T16:28:30.716641Z","end":"2026-07-02T16:28:30.887722Z","steps":["trace[426103361] 'process raft request' (duration: 112.586246ms)","trace[426103361] 'compare' (duration: 58.123073ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:30.887806Z","caller":"traceutil/trace.go:171","msg":"trace[1949414442] linearizableReadLoop","detail":"{readStateIndex:27160; appliedIndex:27159; }","duration":"161.328265ms","start":"2026-07-02T16:28:30.726448Z","end":"2026-07-02T16:28:30.887776Z","steps":["trace[1949414442] 'read index received' (duration: 102.794563ms)","trace[1949414442] 'applied index is now lower than readState.Index' (duration: 58.531162ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:28:30.888178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.703473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-02T16:28:30.88819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.175162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-07-02T16:28:30.888233Z","caller":"traceutil/trace.go:171","msg":"trace[96047394] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26300; }","duration":"115.273474ms","start":"2026-07-02T16:28:30.772947Z","end":"2026-07-02T16:28:30.88822Z","steps":["trace[96047394] 'agreement among raft nodes before linearized reading' (duration: 115.038829ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:30.888228Z","caller":"traceutil/trace.go:171","msg":"trace[1735901911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26300; }","duration":"161.808625ms","start":"2026-07-02T16:28:30.726405Z","end":"2026-07-02T16:28:30.888214Z","steps":["trace[1735901911] 'agreement among raft nodes before linearized reading' (duration: 161.469458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:31.111534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.493175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:28:31.111645Z","caller":"traceutil/trace.go:171","msg":"trace[1158866844] transaction","detail":"{read_only:false; response_revision:26301; number_of_response:1; }","duration":"209.683095ms","start":"2026-07-02T16:28:30.901939Z","end":"2026-07-02T16:28:31.111622Z","steps":["trace[1158866844] 'process raft request' (duration: 102.032667ms)","trace[1158866844] 'compare' (duration: 107.383123ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:31.111807Z","caller":"traceutil/trace.go:171","msg":"trace[576558087] transaction","detail":"{read_only:false; response_revision:26305; number_of_response:1; }","duration":"117.546574ms","start":"2026-07-02T16:28:30.994252Z","end":"2026-07-02T16:28:31.111798Z","steps":["trace[576558087] 'process raft request' (duration: 117.507683ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:31.111821Z","caller":"traceutil/trace.go:171","msg":"trace[281890104] transaction","detail":"{read_only:false; response_revision:26303; number_of_response:1; }","duration":"124.344391ms","start":"2026-07-02T16:28:30.987466Z","end":"2026-07-02T16:28:31.111811Z","steps":["trace[281890104] 'process raft request' (duration: 124.192138ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:31.111874Z","caller":"traceutil/trace.go:171","msg":"trace[428353738] linearizableReadLoop","detail":"{readStateIndex:27162; appliedIndex:27161; }","duration":"173.925949ms","start":"2026-07-02T16:28:30.93794Z","end":"2026-07-02T16:28:31.111866Z","steps":["trace[428353738] 'read index received' (duration: 66.055746ms)","trace[428353738] 'applied index is now lower than readState.Index' (duration: 107.869653ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:31.111872Z","caller":"traceutil/trace.go:171","msg":"trace[246745837] transaction","detail":"{read_only:false; response_revision:26302; number_of_response:1; }","duration":"127.233334ms","start":"2026-07-02T16:28:30.984609Z","end":"2026-07-02T16:28:31.111843Z","steps":["trace[246745837] 'process raft request' (duration: 127.009079ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:31.111899Z","caller":"traceutil/trace.go:171","msg":"trace[1132364926] transaction","detail":"{read_only:false; response_revision:26304; number_of_response:1; }","duration":"123.095715ms","start":"2026-07-02T16:28:30.988773Z","end":"2026-07-02T16:28:31.111869Z","steps":["trace[1132364926] 'process raft request' (duration: 122.958272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:31.112003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.063431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-07-02T16:28:31.112021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.724275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T16:28:31.112037Z","caller":"traceutil/trace.go:171","msg":"trace[609561593] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26305; }","duration":"174.153843ms","start":"2026-07-02T16:28:30.937875Z","end":"2026-07-02T16:28:31.112029Z","steps":["trace[609561593] 'agreement among raft nodes before linearized reading' (duration: 174.036951ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:31.112045Z","caller":"traceutil/trace.go:171","msg":"trace[1807375026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26305; }","duration":"173.778425ms","start":"2026-07-02T16:28:30.93826Z","end":"2026-07-02T16:28:31.112038Z","steps":["trace[1807375026] 'agreement among raft nodes before linearized reading' (duration: 173.703644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:49.105498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.506553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:49.105585Z","caller":"traceutil/trace.go:171","msg":"trace[1119454828] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26446; }","duration":"146.638425ms","start":"2026-07-02T16:28:48.958925Z","end":"2026-07-02T16:28:49.105563Z","steps":["trace[1119454828] 'count revisions from in-memory index tree' (duration: 146.38964ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:49.105857Z","caller":"traceutil/trace.go:171","msg":"trace[756910028] transaction","detail":"{read_only:false; response_revision:26447; number_of_response:1; }","duration":"131.833314ms","start":"2026-07-02T16:28:48.973981Z","end":"2026-07-02T16:28:49.105815Z","steps":["trace[756910028] 'process raft request' (duration: 112.428843ms)","trace[756910028] 'compare' (duration: 19.056754ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:49.294863Z","caller":"traceutil/trace.go:171","msg":"trace[1686693600] transaction","detail":"{read_only:false; response_revision:26448; number_of_response:1; }","duration":"117.217306ms","start":"2026-07-02T16:28:49.177617Z","end":"2026-07-02T16:28:49.294834Z","steps":["trace[1686693600] 'process raft request' (duration: 42.499053ms)","trace[1686693600] 'compare' (duration: 74.534259ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:49.36372Z","caller":"traceutil/trace.go:171","msg":"trace[997663203] transaction","detail":"{read_only:false; response_revision:26450; number_of_response:1; }","duration":"184.13937ms","start":"2026-07-02T16:28:49.179553Z","end":"2026-07-02T16:28:49.363692Z","steps":["trace[997663203] 'process raft request' (duration: 184.012157ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:49.363725Z","caller":"traceutil/trace.go:171","msg":"trace[138346295] transaction","detail":"{read_only:false; response_revision:26449; number_of_response:1; }","duration":"184.489798ms","start":"2026-07-02T16:28:49.179197Z","end":"2026-07-02T16:28:49.363687Z","steps":["trace[138346295] 'process raft request' (duration: 184.199841ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:49.363891Z","caller":"traceutil/trace.go:171","msg":"trace[1876119669] linearizableReadLoop","detail":"{readStateIndex:27316; appliedIndex:27312; }","duration":"174.073642ms","start":"2026-07-02T16:28:49.189772Z","end":"2026-07-02T16:28:49.363846Z","steps":["trace[1876119669] 'read index received' (duration: 30.35849ms)","trace[1876119669] 'applied index is now lower than readState.Index' (duration: 143.708852ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:49.36396Z","caller":"traceutil/trace.go:171","msg":"trace[1687103594] transaction","detail":"{read_only:false; response_revision:26451; number_of_response:1; }","duration":"184.285782ms","start":"2026-07-02T16:28:49.179624Z","end":"2026-07-02T16:28:49.36391Z","steps":["trace[1687103594] 'process raft request' (duration: 184.011047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:49.364084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.300137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-02T16:28:49.36413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.637092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:28:49.36416Z","caller":"traceutil/trace.go:171","msg":"trace[1318444405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26451; }","duration":"174.41138ms","start":"2026-07-02T16:28:49.189728Z","end":"2026-07-02T16:28:49.364139Z","steps":["trace[1318444405] 'agreement among raft nodes before linearized reading' (duration: 174.236076ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:49.364193Z","caller":"traceutil/trace.go:171","msg":"trace[1649682129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26451; }","duration":"155.735894ms","start":"2026-07-02T16:28:49.20844Z","end":"2026-07-02T16:28:49.364176Z","steps":["trace[1649682129] 'agreement among raft nodes before linearized reading' (duration: 155.6131ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:49.51566Z","caller":"traceutil/trace.go:171","msg":"trace[1728662280] transaction","detail":"{read_only:false; response_revision:26453; number_of_response:1; }","duration":"145.536992ms","start":"2026-07-02T16:28:49.370088Z","end":"2026-07-02T16:28:49.515625Z","steps":["trace[1728662280] 'process raft request' (duration: 145.214394ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:49.515749Z","caller":"traceutil/trace.go:171","msg":"trace[1115197890] transaction","detail":"{read_only:false; response_revision:26454; number_of_response:1; }","duration":"144.551581ms","start":"2026-07-02T16:28:49.371143Z","end":"2026-07-02T16:28:49.515694Z","steps":["trace[1115197890] 'process raft request' (duration: 144.278095ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:49.515714Z","caller":"traceutil/trace.go:171","msg":"trace[1333359505] transaction","detail":"{read_only:false; response_revision:26452; number_of_response:1; }","duration":"145.749216ms","start":"2026-07-02T16:28:49.369882Z","end":"2026-07-02T16:28:49.515632Z","steps":["trace[1333359505] 'process raft request' (duration: 69.497219ms)","trace[1333359505] 'compare' (duration: 75.675284ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:51.421853Z","caller":"traceutil/trace.go:171","msg":"trace[1229800539] transaction","detail":"{read_only:false; response_revision:26463; number_of_response:1; }","duration":"122.515272ms","start":"2026-07-02T16:28:51.299271Z","end":"2026-07-02T16:28:51.421787Z","steps":["trace[1229800539] 'process raft request' (duration: 122.266377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:51.625171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.925717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:28:51.625468Z","caller":"traceutil/trace.go:171","msg":"trace[1190031864] linearizableReadLoop","detail":"{readStateIndex:27331; appliedIndex:27328; }","duration":"149.936278ms","start":"2026-07-02T16:28:51.475507Z","end":"2026-07-02T16:28:51.625444Z","steps":["trace[1190031864] 'read index received' (duration: 11.5107ms)","trace[1190031864] 'applied index is now lower than readState.Index' (duration: 138.424198ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:51.625505Z","caller":"traceutil/trace.go:171","msg":"trace[1837791057] transaction","detail":"{read_only:false; response_revision:26466; number_of_response:1; }","duration":"253.838475ms","start":"2026-07-02T16:28:51.371624Z","end":"2026-07-02T16:28:51.625462Z","steps":["trace[1837791057] 'process raft request' (duration: 253.751753ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:51.625502Z","caller":"traceutil/trace.go:171","msg":"trace[504156056] transaction","detail":"{read_only:false; response_revision:26465; number_of_response:1; }","duration":"255.44727ms","start":"2026-07-02T16:28:51.37003Z","end":"2026-07-02T16:28:51.625477Z","steps":["trace[504156056] 'process raft request' (duration: 255.256456ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:51.625454Z","caller":"traceutil/trace.go:171","msg":"trace[715554274] transaction","detail":"{read_only:false; response_revision:26464; number_of_response:1; }","duration":"256.047883ms","start":"2026-07-02T16:28:51.369373Z","end":"2026-07-02T16:28:51.62542Z","steps":["trace[715554274] 'process raft request' (duration: 117.702617ms)","trace[715554274] 'compare' (duration: 137.782154ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:28:51.625794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.271445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-02T16:28:51.625883Z","caller":"traceutil/trace.go:171","msg":"trace[26393799] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:26466; }","duration":"150.396868ms","start":"2026-07-02T16:28:51.475465Z","end":"2026-07-02T16:28:51.625862Z","steps":["trace[26393799] 'agreement among raft nodes before linearized reading' (duration: 150.092041ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:51.702359Z","caller":"traceutil/trace.go:171","msg":"trace[1431412385] transaction","detail":"{read_only:false; response_revision:26467; number_of_response:1; }","duration":"156.574882ms","start":"2026-07-02T16:28:51.545749Z","end":"2026-07-02T16:28:51.702324Z","steps":["trace[1431412385] 'process raft request' (duration: 156.443179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:51.702523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.107386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T16:28:51.702572Z","caller":"traceutil/trace.go:171","msg":"trace[1948752080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26467; }","duration":"182.193318ms","start":"2026-07-02T16:28:51.520363Z","end":"2026-07-02T16:28:51.702557Z","steps":["trace[1948752080] 'agreement among raft nodes before linearized reading' (duration: 182.104766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:51.702431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.114817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-02T16:28:51.702613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.213559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-02T16:28:51.702645Z","caller":"traceutil/trace.go:171","msg":"trace[1651440531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26467; }","duration":"182.332151ms","start":"2026-07-02T16:28:51.520294Z","end":"2026-07-02T16:28:51.702626Z","steps":["trace[1651440531] 'agreement among raft nodes before linearized reading' (duration: 182.006854ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:51.702669Z","caller":"traceutil/trace.go:171","msg":"trace[926335528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26467; }","duration":"182.307451ms","start":"2026-07-02T16:28:51.520337Z","end":"2026-07-02T16:28:51.702644Z","steps":["trace[926335528] 'agreement among raft nodes before linearized reading' (duration: 182.163878ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:52.406655Z","caller":"traceutil/trace.go:171","msg":"trace[1202640853] transaction","detail":"{read_only:false; response_revision:26473; number_of_response:1; }","duration":"159.644009ms","start":"2026-07-02T16:28:52.246985Z","end":"2026-07-02T16:28:52.406629Z","steps":["trace[1202640853] 'process raft request' (duration: 159.496386ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:52.511037Z","caller":"traceutil/trace.go:171","msg":"trace[1801965544] transaction","detail":"{read_only:false; response_revision:26474; number_of_response:1; }","duration":"181.36836ms","start":"2026-07-02T16:28:52.329628Z","end":"2026-07-02T16:28:52.510996Z","steps":["trace[1801965544] 'process raft request' (duration: 108.17847ms)","trace[1801965544] 'compare' (duration: 72.897664ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:57.750554Z","caller":"traceutil/trace.go:171","msg":"trace[514747702] transaction","detail":"{read_only:false; response_revision:26507; number_of_response:1; }","duration":"109.56308ms","start":"2026-07-02T16:28:57.640959Z","end":"2026-07-02T16:28:57.750522Z","steps":["trace[514747702] 'process raft request' (duration: 109.423357ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:57.874088Z","caller":"traceutil/trace.go:171","msg":"trace[1150639952] transaction","detail":"{read_only:false; response_revision:26509; number_of_response:1; }","duration":"229.764952ms","start":"2026-07-02T16:28:57.644299Z","end":"2026-07-02T16:28:57.874063Z","steps":["trace[1150639952] 'process raft request' (duration: 229.687291ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:57.874222Z","caller":"traceutil/trace.go:171","msg":"trace[1286645565] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"230.377475ms","start":"2026-07-02T16:28:57.643802Z","end":"2026-07-02T16:28:57.874179Z","steps":["trace[1286645565] 'process raft request' (duration: 230.038957ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:28:57.874268Z","caller":"traceutil/trace.go:171","msg":"trace[1574063942] linearizableReadLoop","detail":"{readStateIndex:27377; appliedIndex:27373; }","duration":"126.009158ms","start":"2026-07-02T16:28:57.748235Z","end":"2026-07-02T16:28:57.874244Z","steps":["trace[1574063942] 'read index received' (duration: 2.203858ms)","trace[1574063942] 'applied index is now lower than readState.Index' (duration: 123.80357ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:28:57.874402Z","caller":"traceutil/trace.go:171","msg":"trace[1578496602] transaction","detail":"{read_only:false; response_revision:26510; number_of_response:1; }","duration":"151.622975ms","start":"2026-07-02T16:28:57.722739Z","end":"2026-07-02T16:28:57.874362Z","steps":["trace[1578496602] 'process raft request' (duration: 151.289707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:28:57.874482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.232673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T16:28:57.874517Z","caller":"traceutil/trace.go:171","msg":"trace[730933992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26510; }","duration":"126.320075ms","start":"2026-07-02T16:28:57.748185Z","end":"2026-07-02T16:28:57.874505Z","steps":["trace[730933992] 'agreement among raft nodes before linearized reading' (duration: 126.178192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:29:03.989529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.10066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:29:03.989661Z","caller":"traceutil/trace.go:171","msg":"trace[137506852] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26554; }","duration":"109.272404ms","start":"2026-07-02T16:29:03.880365Z","end":"2026-07-02T16:29:03.989637Z","steps":["trace[137506852] 'range keys from in-memory index tree' (duration: 109.050239ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:04.023408Z","caller":"traceutil/trace.go:171","msg":"trace[2072161710] transaction","detail":"{read_only:false; response_revision:26556; number_of_response:1; }","duration":"127.419888ms","start":"2026-07-02T16:29:03.895958Z","end":"2026-07-02T16:29:04.023378Z","steps":["trace[2072161710] 'process raft request' (duration: 127.368977ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:04.023688Z","caller":"traceutil/trace.go:171","msg":"trace[835585136] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"127.688783ms","start":"2026-07-02T16:29:03.895946Z","end":"2026-07-02T16:29:04.023635Z","steps":["trace[835585136] 'process raft request' (duration: 127.225784ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:04.027493Z","caller":"traceutil/trace.go:171","msg":"trace[1429689432] linearizableReadLoop","detail":"{readStateIndex:27425; appliedIndex:27422; }","duration":"122.800308ms","start":"2026-07-02T16:29:03.904667Z","end":"2026-07-02T16:29:04.027467Z","steps":["trace[1429689432] 'read index received' (duration: 118.496884ms)","trace[1429689432] 'applied index is now lower than readState.Index' (duration: 4.293574ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:29:04.027615Z","caller":"traceutil/trace.go:171","msg":"trace[1009835044] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"129.599605ms","start":"2026-07-02T16:29:03.898003Z","end":"2026-07-02T16:29:04.027602Z","steps":["trace[1009835044] 'process raft request' (duration: 129.322229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:29:04.027872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.187236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T16:29:04.027916Z","caller":"traceutil/trace.go:171","msg":"trace[1285568794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26557; }","duration":"123.295379ms","start":"2026-07-02T16:29:03.904606Z","end":"2026-07-02T16:29:04.027901Z","steps":["trace[1285568794] 'agreement among raft nodes before linearized reading' (duration: 123.121885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:29:04.028092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.139034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T16:29:04.028132Z","caller":"traceutil/trace.go:171","msg":"trace[461495092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26557; }","duration":"122.262216ms","start":"2026-07-02T16:29:03.905861Z","end":"2026-07-02T16:29:04.028123Z","steps":["trace[461495092] 'agreement among raft nodes before linearized reading' (duration: 122.133404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:29:04.028363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.116929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-02T16:29:04.028406Z","caller":"traceutil/trace.go:171","msg":"trace[798866089] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26557; }","duration":"114.206671ms","start":"2026-07-02T16:29:03.914187Z","end":"2026-07-02T16:29:04.028393Z","steps":["trace[798866089] 'agreement among raft nodes before linearized reading' (duration: 114.093019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:29:04.232451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.559229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T16:29:04.232715Z","caller":"traceutil/trace.go:171","msg":"trace[499164536] transaction","detail":"{read_only:false; response_revision:26558; number_of_response:1; }","duration":"197.362777ms","start":"2026-07-02T16:29:04.035317Z","end":"2026-07-02T16:29:04.23268Z","steps":["trace[499164536] 'process raft request' (duration: 70.43428ms)","trace[499164536] 'compare' (duration: 126.296034ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:29:04.232858Z","caller":"traceutil/trace.go:171","msg":"trace[53055488] linearizableReadLoop","detail":"{readStateIndex:27428; appliedIndex:27425; }","duration":"196.806086ms","start":"2026-07-02T16:29:04.036025Z","end":"2026-07-02T16:29:04.232831Z","steps":["trace[53055488] 'read index received' (duration: 69.709245ms)","trace[53055488] 'applied index is now lower than readState.Index' (duration: 127.094851ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:29:04.232987Z","caller":"traceutil/trace.go:171","msg":"trace[507646489] transaction","detail":"{read_only:false; response_revision:26561; number_of_response:1; }","duration":"154.725372ms","start":"2026-07-02T16:29:04.078252Z","end":"2026-07-02T16:29:04.232977Z","steps":["trace[507646489] 'process raft request' (duration: 154.516617ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:04.233266Z","caller":"traceutil/trace.go:171","msg":"trace[622007964] transaction","detail":"{read_only:false; response_revision:26559; number_of_response:1; }","duration":"197.91769ms","start":"2026-07-02T16:29:04.035342Z","end":"2026-07-02T16:29:04.23326Z","steps":["trace[622007964] 'process raft request' (duration: 197.284906ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:04.23334Z","caller":"traceutil/trace.go:171","msg":"trace[1916729860] transaction","detail":"{read_only:false; response_revision:26560; number_of_response:1; }","duration":"197.97554ms","start":"2026-07-02T16:29:04.035359Z","end":"2026-07-02T16:29:04.233334Z","steps":["trace[1916729860] 'process raft request' (duration: 197.348647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:29:04.233498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.370155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T16:29:04.233522Z","caller":"traceutil/trace.go:171","msg":"trace[1366712177] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:26561; }","duration":"120.501258ms","start":"2026-07-02T16:29:04.113016Z","end":"2026-07-02T16:29:04.233517Z","steps":["trace[1366712177] 'agreement among raft nodes before linearized reading' (duration: 120.389546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:29:04.233925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.88978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18353"} {"level":"info","ts":"2026-07-02T16:29:04.234Z","caller":"traceutil/trace.go:171","msg":"trace[622226346] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26561; }","duration":"197.979801ms","start":"2026-07-02T16:29:04.036003Z","end":"2026-07-02T16:29:04.233982Z","steps":["trace[622226346] 'agreement among raft nodes before linearized reading' (duration: 197.814928ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:04.382742Z","caller":"traceutil/trace.go:171","msg":"trace[1368061902] linearizableReadLoop","detail":"{readStateIndex:27430; appliedIndex:27429; }","duration":"133.313677ms","start":"2026-07-02T16:29:04.249384Z","end":"2026-07-02T16:29:04.382698Z","steps":["trace[1368061902] 'read index received' (duration: 123.504874ms)","trace[1368061902] 'applied index is now lower than readState.Index' (duration: 9.807083ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:29:04.383168Z","caller":"traceutil/trace.go:171","msg":"trace[1241341637] transaction","detail":"{read_only:false; response_revision:26562; number_of_response:1; }","duration":"144.192723ms","start":"2026-07-02T16:29:04.23892Z","end":"2026-07-02T16:29:04.383113Z","steps":["trace[1241341637] 'process raft request' (duration: 134.028512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:29:04.383181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.975461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:29:04.383273Z","caller":"traceutil/trace.go:171","msg":"trace[685379370] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:26562; }","duration":"111.094894ms","start":"2026-07-02T16:29:04.272156Z","end":"2026-07-02T16:29:04.383251Z","steps":["trace[685379370] 'agreement among raft nodes before linearized reading' (duration: 110.91859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:29:04.383359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.92667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-02T16:29:04.383442Z","caller":"traceutil/trace.go:171","msg":"trace[158027152] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26562; }","duration":"134.069022ms","start":"2026-07-02T16:29:04.249348Z","end":"2026-07-02T16:29:04.383417Z","steps":["trace[158027152] 'agreement among raft nodes before linearized reading' (duration: 133.752926ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:08.540156Z","caller":"traceutil/trace.go:171","msg":"trace[858728671] transaction","detail":"{read_only:false; response_revision:26598; number_of_response:1; }","duration":"207.192951ms","start":"2026-07-02T16:29:08.332928Z","end":"2026-07-02T16:29:08.540121Z","steps":["trace[858728671] 'process raft request' (duration: 174.427059ms)","trace[858728671] 'compare' (duration: 32.519007ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:29:08.54051Z","caller":"traceutil/trace.go:171","msg":"trace[331483828] transaction","detail":"{read_only:false; response_revision:26599; number_of_response:1; }","duration":"207.531249ms","start":"2026-07-02T16:29:08.332938Z","end":"2026-07-02T16:29:08.54047Z","steps":["trace[331483828] 'process raft request' (duration: 207.11577ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:08.541333Z","caller":"traceutil/trace.go:171","msg":"trace[1790566965] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"208.353926ms","start":"2026-07-02T16:29:08.332939Z","end":"2026-07-02T16:29:08.541293Z","steps":["trace[1790566965] 'process raft request' (duration: 208.124101ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:08.541342Z","caller":"traceutil/trace.go:171","msg":"trace[1408635713] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"113.356973ms","start":"2026-07-02T16:29:08.427954Z","end":"2026-07-02T16:29:08.541311Z","steps":["trace[1408635713] 'process raft request' (duration: 113.252311ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:08.541356Z","caller":"traceutil/trace.go:171","msg":"trace[1426234475] linearizableReadLoop","detail":"{readStateIndex:27469; appliedIndex:27466; }","duration":"145.434259ms","start":"2026-07-02T16:29:08.395898Z","end":"2026-07-02T16:29:08.541333Z","steps":["trace[1426234475] 'read index received' (duration: 111.471312ms)","trace[1426234475] 'applied index is now lower than readState.Index' (duration: 33.961327ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:29:08.541677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.761837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T16:29:08.541745Z","caller":"traceutil/trace.go:171","msg":"trace[499194018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26601; }","duration":"145.89333ms","start":"2026-07-02T16:29:08.395835Z","end":"2026-07-02T16:29:08.541728Z","steps":["trace[499194018] 'agreement among raft nodes before linearized reading' (duration: 145.569863ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:08.781609Z","caller":"traceutil/trace.go:171","msg":"trace[1604717185] transaction","detail":"{read_only:false; response_revision:26603; number_of_response:1; }","duration":"215.108464ms","start":"2026-07-02T16:29:08.566467Z","end":"2026-07-02T16:29:08.781576Z","steps":["trace[1604717185] 'process raft request' (duration: 136.932445ms)","trace[1604717185] 'compare' (duration: 77.899713ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:29:14.119176Z","caller":"traceutil/trace.go:171","msg":"trace[163666222] transaction","detail":"{read_only:false; response_revision:26637; number_of_response:1; }","duration":"187.419772ms","start":"2026-07-02T16:29:13.931724Z","end":"2026-07-02T16:29:14.119144Z","steps":["trace[163666222] 'process raft request' (duration: 137.786544ms)","trace[163666222] 'compare' (duration: 48.914322ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:29:14.17903Z","caller":"traceutil/trace.go:171","msg":"trace[17407516] transaction","detail":"{read_only:false; response_revision:26638; number_of_response:1; }","duration":"120.681212ms","start":"2026-07-02T16:29:14.058321Z","end":"2026-07-02T16:29:14.179003Z","steps":["trace[17407516] 'process raft request' (duration: 120.432617ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:14.179505Z","caller":"traceutil/trace.go:171","msg":"trace[554091820] transaction","detail":"{read_only:false; response_revision:26639; number_of_response:1; }","duration":"112.919264ms","start":"2026-07-02T16:29:14.066544Z","end":"2026-07-02T16:29:14.179463Z","steps":["trace[554091820] 'process raft request' (duration: 112.412973ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:14.180137Z","caller":"traceutil/trace.go:171","msg":"trace[864321791] transaction","detail":"{read_only:false; response_revision:26640; number_of_response:1; }","duration":"113.408523ms","start":"2026-07-02T16:29:14.066708Z","end":"2026-07-02T16:29:14.180117Z","steps":["trace[864321791] 'process raft request' (duration: 112.683287ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:14.864943Z","caller":"traceutil/trace.go:171","msg":"trace[1970098076] transaction","detail":"{read_only:false; response_revision:26645; number_of_response:1; }","duration":"209.287826ms","start":"2026-07-02T16:29:14.655627Z","end":"2026-07-02T16:29:14.864915Z","steps":["trace[1970098076] 'process raft request' (duration: 208.681113ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:14.865416Z","caller":"traceutil/trace.go:171","msg":"trace[1389768066] linearizableReadLoop","detail":"{readStateIndex:27515; appliedIndex:27513; }","duration":"208.204633ms","start":"2026-07-02T16:29:14.6572Z","end":"2026-07-02T16:29:14.865404Z","steps":["trace[1389768066] 'read index received' (duration: 64.106642ms)","trace[1389768066] 'applied index is now lower than readState.Index' (duration: 144.096981ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:29:14.865561Z","caller":"traceutil/trace.go:171","msg":"trace[468083642] transaction","detail":"{read_only:false; response_revision:26646; number_of_response:1; }","duration":"177.915116ms","start":"2026-07-02T16:29:14.687638Z","end":"2026-07-02T16:29:14.865553Z","steps":["trace[468083642] 'process raft request' (duration: 176.911473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:29:14.86574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.522941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-02T16:29:14.865763Z","caller":"traceutil/trace.go:171","msg":"trace[1076615097] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26646; }","duration":"208.578032ms","start":"2026-07-02T16:29:14.657178Z","end":"2026-07-02T16:29:14.865756Z","steps":["trace[1076615097] 'agreement among raft nodes before linearized reading' (duration: 208.456829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:29:14.874813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.639038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:29:14.874887Z","caller":"traceutil/trace.go:171","msg":"trace[301478668] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:26647; }","duration":"147.759181ms","start":"2026-07-02T16:29:14.727107Z","end":"2026-07-02T16:29:14.874866Z","steps":["trace[301478668] 'agreement among raft nodes before linearized reading' (duration: 147.645718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:29:15.184499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.976748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T16:29:15.18461Z","caller":"traceutil/trace.go:171","msg":"trace[2024243272] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:26648; }","duration":"109.161012ms","start":"2026-07-02T16:29:15.075422Z","end":"2026-07-02T16:29:15.184583Z","steps":["trace[2024243272] 'count revisions from in-memory index tree' (duration: 108.847605ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:29:15.808566Z","caller":"traceutil/trace.go:171","msg":"trace[1337539252] transaction","detail":"{read_only:false; response_revision:26651; number_of_response:1; }","duration":"128.652645ms","start":"2026-07-02T16:29:15.679877Z","end":"2026-07-02T16:29:15.808529Z","steps":["trace[1337539252] 'process raft request' (duration: 128.485632ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:31:24.265701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25098} {"level":"info","ts":"2026-07-02T16:31:24.411469Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25098,"took":"141.650429ms","hash":3540258183,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":37601280,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-02T16:31:24.411546Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3540258183,"revision":25098,"compact-revision":21918} {"level":"info","ts":"2026-07-02T16:33:16.467814Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T16:33:16.474896Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-07-02T16:33:16.475168Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-07-02T16:36:24.303058Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27933} {"level":"info","ts":"2026-07-02T16:36:24.464649Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27933,"took":"157.094805ms","hash":2793783030,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":33832960,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-02T16:36:24.464726Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2793783030,"revision":27933,"compact-revision":25098} {"level":"info","ts":"2026-07-02T16:41:24.310677Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30504} {"level":"info","ts":"2026-07-02T16:41:24.454879Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30504,"took":"138.969691ms","hash":2040126791,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":30642176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T16:41:24.45496Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2040126791,"revision":30504,"compact-revision":27933} {"level":"info","ts":"2026-07-02T16:46:24.318098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32756} {"level":"info","ts":"2026-07-02T16:46:24.481019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32756,"took":"158.111857ms","hash":701045564,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":30314496,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T16:46:24.481106Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":701045564,"revision":32756,"compact-revision":30504} {"level":"info","ts":"2026-07-02T16:51:24.326615Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34987} {"level":"info","ts":"2026-07-02T16:51:24.470703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34987,"took":"137.756814ms","hash":3902926140,"current-db-size-bytes":58802176,"current-db-size":"59 MB","current-db-size-in-use-bytes":31956992,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T16:51:24.470763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3902926140,"revision":34987,"compact-revision":32756} {"level":"info","ts":"2026-07-02T16:54:44.524926Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T16:54:44.531166Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-07-02T16:54:44.531299Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-07-02T16:56:24.33222Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37287} {"level":"info","ts":"2026-07-02T16:56:24.451655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37287,"took":"115.390438ms","hash":4131948216,"current-db-size-bytes":59228160,"current-db-size":"59 MB","current-db-size-in-use-bytes":32858112,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-02T16:56:24.451735Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4131948216,"revision":37287,"compact-revision":34987} {"level":"info","ts":"2026-07-02T17:01:24.339869Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39566} {"level":"info","ts":"2026-07-02T17:01:24.471401Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39566,"took":"127.94267ms","hash":1024796648,"current-db-size-bytes":59650048,"current-db-size":"60 MB","current-db-size-in-use-bytes":32768000,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-02T17:01:24.471484Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1024796648,"revision":39566,"compact-revision":37287}