{"level":"warn","ts":"2026-07-14T22:49:58.430247Z","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-14T22:49:58.430349Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-14T22:49:58.430417Z","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-14T22:49:58.43043Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-07-14T22:49:58.430447Z","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-14T22:49:58.431044Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-07-14T22:49:58.431117Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-14T22:49:58.435253Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.861318ms"} {"level":"info","ts":"2026-07-14T22:49:58.442662Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-07-14T22:49:58.442781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T22:49:58.442817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-07-14T22:49:58.442831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T22:49:58.442841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-07-14T22:49:58.442896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-07-14T22:49:58.450399Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T22:49:58.452034Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T22:49:58.454369Z","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-14T22:49:58.458307Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T22:49:58.458647Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T22:49:58.458844Z","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-14T22:49:58.458963Z","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-14T22:49:58.45898Z","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-14T22:49:58.461638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-07-14T22:49:58.464645Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-07-14T22:49:58.468559Z","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-14T22:49:58.468742Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-07-14T22:49:58.468816Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-07-14T22:49:58.468868Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T22:49:58.468925Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T22:49:59.343047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T22:49:59.34313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T22:49:59.343173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-07-14T22:49:59.34319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-07-14T22:49:59.343198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-07-14T22:49:59.343489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-07-14T22:49:59.343558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-07-14T22:49:59.344689Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:49:59.345869Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T22:49:59.346008Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T22:49:59.346048Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T22:49:59.346192Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T22:49:59.346233Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T22:49:59.346351Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:49:59.346517Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:49:59.34658Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:49:59.348113Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T22:49:59.349643Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"warn","ts":"2026-07-14T22:50:09.66147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.345429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:50:09.661585Z","caller":"traceutil/trace.go:171","msg":"trace[1385719127] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:324; }","duration":"264.098399ms","start":"2026-07-14T22:50:09.397463Z","end":"2026-07-14T22:50:09.661562Z","steps":["trace[1385719127] 'read index received' (duration: 137.92µs)","trace[1385719127] 'applied index is now lower than readState.Index' (duration: 263.959649ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:50:09.661675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.207369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-07-14T22:50:09.661699Z","caller":"traceutil/trace.go:171","msg":"trace[253959222] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:310; }","duration":"264.247309ms","start":"2026-07-14T22:50:09.397445Z","end":"2026-07-14T22:50:09.661692Z","steps":["trace[253959222] 'agreement among raft nodes before linearized reading' (duration: 264.199959ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:09.661668Z","caller":"traceutil/trace.go:171","msg":"trace[1649988891] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"290.82388ms","start":"2026-07-14T22:50:09.370803Z","end":"2026-07-14T22:50:09.661627Z","steps":["trace[1649988891] 'process raft request' (duration: 26.881381ms)","trace[1649988891] 'compare' (duration: 263.251239ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:50:09.661912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.668575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-14T22:50:09.661952Z","caller":"traceutil/trace.go:171","msg":"trace[2030163002] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:310; }","duration":"211.707915ms","start":"2026-07-14T22:50:09.450234Z","end":"2026-07-14T22:50:09.661942Z","steps":["trace[2030163002] 'agreement among raft nodes before linearized reading' (duration: 211.645475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:09.662166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.885325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-14T22:50:09.662197Z","caller":"traceutil/trace.go:171","msg":"trace[1267827069] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:310; }","duration":"112.948035ms","start":"2026-07-14T22:50:09.549241Z","end":"2026-07-14T22:50:09.662189Z","steps":["trace[1267827069] 'agreement among raft nodes before linearized reading' (duration: 112.904465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:09.662325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.40753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-07-14T22:50:09.662404Z","caller":"traceutil/trace.go:171","msg":"trace[1381327571] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:310; }","duration":"164.60949ms","start":"2026-07-14T22:50:09.497769Z","end":"2026-07-14T22:50:09.662379Z","steps":["trace[1381327571] 'agreement among raft nodes before linearized reading' (duration: 164.48861ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:09.855674Z","caller":"traceutil/trace.go:171","msg":"trace[1076808827] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"142.455126ms","start":"2026-07-14T22:50:09.713178Z","end":"2026-07-14T22:50:09.855633Z","steps":["trace[1076808827] 'process raft request' (duration: 130.29715ms)","trace[1076808827] 'compare' (duration: 11.588427ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:50:09.856011Z","caller":"traceutil/trace.go:171","msg":"trace[1111682676] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"142.753456ms","start":"2026-07-14T22:50:09.713236Z","end":"2026-07-14T22:50:09.855989Z","steps":["trace[1111682676] 'process raft request' (duration: 142.192816ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:09.856011Z","caller":"traceutil/trace.go:171","msg":"trace[1899194415] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"142.642616ms","start":"2026-07-14T22:50:09.713316Z","end":"2026-07-14T22:50:09.855959Z","steps":["trace[1899194415] 'process raft request' (duration: 142.193116ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:09.856134Z","caller":"traceutil/trace.go:171","msg":"trace[1922184644] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"139.864857ms","start":"2026-07-14T22:50:09.716244Z","end":"2026-07-14T22:50:09.856109Z","steps":["trace[1922184644] 'process raft request' (duration: 139.310827ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:09.85652Z","caller":"traceutil/trace.go:171","msg":"trace[1720262695] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:333; }","duration":"142.811516ms","start":"2026-07-14T22:50:09.713689Z","end":"2026-07-14T22:50:09.8565Z","steps":["trace[1720262695] 'read index received' (duration: 26.538602ms)","trace[1720262695] 'applied index is now lower than readState.Index' (duration: 116.271144ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:50:09.856564Z","caller":"traceutil/trace.go:171","msg":"trace[1577591266] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"139.998037ms","start":"2026-07-14T22:50:09.716549Z","end":"2026-07-14T22:50:09.856547Z","steps":["trace[1577591266] 'process raft request' (duration: 139.133397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:09.856716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.000156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-14T22:50:09.856892Z","caller":"traceutil/trace.go:171","msg":"trace[511473817] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:324; }","duration":"143.197856ms","start":"2026-07-14T22:50:09.713664Z","end":"2026-07-14T22:50:09.856861Z","steps":["trace[511473817] 'agreement among raft nodes before linearized reading' (duration: 142.974256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:09.857131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.067046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-07-14T22:50:09.857221Z","caller":"traceutil/trace.go:171","msg":"trace[422024936] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:324; }","duration":"111.195356ms","start":"2026-07-14T22:50:09.746009Z","end":"2026-07-14T22:50:09.857204Z","steps":["trace[422024936] 'agreement among raft nodes before linearized reading' (duration: 110.691936ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:10.010016Z","caller":"traceutil/trace.go:171","msg":"trace[1674696114] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"143.394176ms","start":"2026-07-14T22:50:09.866599Z","end":"2026-07-14T22:50:10.009993Z","steps":["trace[1674696114] 'process raft request' (duration: 143.244396ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:10.010084Z","caller":"traceutil/trace.go:171","msg":"trace[1527504879] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"143.612966ms","start":"2026-07-14T22:50:09.86644Z","end":"2026-07-14T22:50:10.010053Z","steps":["trace[1527504879] 'process raft request' (duration: 131.81045ms)","trace[1527504879] 'compare' (duration: 11.461456ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:50:10.010131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.649826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:2278"} {"level":"info","ts":"2026-07-14T22:50:10.010204Z","caller":"traceutil/trace.go:171","msg":"trace[1164699127] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:331; }","duration":"143.764856ms","start":"2026-07-14T22:50:09.86643Z","end":"2026-07-14T22:50:10.010195Z","steps":["trace[1164699127] 'agreement among raft nodes before linearized reading' (duration: 143.635866ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:10.010316Z","caller":"traceutil/trace.go:171","msg":"trace[507230492] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"142.994166ms","start":"2026-07-14T22:50:09.8673Z","end":"2026-07-14T22:50:10.010294Z","steps":["trace[507230492] 'process raft request' (duration: 142.568596ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:10.010581Z","caller":"traceutil/trace.go:171","msg":"trace[1645453521] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"144.119006ms","start":"2026-07-14T22:50:09.866452Z","end":"2026-07-14T22:50:10.010571Z","steps":["trace[1645453521] 'process raft request' (duration: 143.364746ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:10.010684Z","caller":"traceutil/trace.go:171","msg":"trace[1363618324] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"140.756297ms","start":"2026-07-14T22:50:09.86991Z","end":"2026-07-14T22:50:10.010666Z","steps":["trace[1363618324] 'process raft request' (duration: 140.030157ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:10.010787Z","caller":"traceutil/trace.go:171","msg":"trace[680485621] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"141.291117ms","start":"2026-07-14T22:50:09.869487Z","end":"2026-07-14T22:50:10.010778Z","steps":["trace[680485621] 'process raft request' (duration: 140.417707ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:10.010024Z","caller":"traceutil/trace.go:171","msg":"trace[890645262] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:339; }","duration":"143.515066ms","start":"2026-07-14T22:50:09.866463Z","end":"2026-07-14T22:50:10.009978Z","steps":["trace[890645262] 'read index received' (duration: 55.407333ms)","trace[890645262] 'applied index is now lower than readState.Index' (duration: 88.106953ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:50:10.223481Z","caller":"traceutil/trace.go:171","msg":"trace[906458057] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"204.988267ms","start":"2026-07-14T22:50:10.018447Z","end":"2026-07-14T22:50:10.223435Z","steps":["trace[906458057] 'process raft request' (duration: 178.077666ms)","trace[906458057] 'compare' (duration: 26.622962ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:50:10.223514Z","caller":"traceutil/trace.go:171","msg":"trace[446802055] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"204.833237ms","start":"2026-07-14T22:50:10.018656Z","end":"2026-07-14T22:50:10.22349Z","steps":["trace[446802055] 'process raft request' (duration: 204.691017ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:10.22362Z","caller":"traceutil/trace.go:171","msg":"trace[1908514463] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:348; }","duration":"203.781457ms","start":"2026-07-14T22:50:10.019819Z","end":"2026-07-14T22:50:10.2236Z","steps":["trace[1908514463] 'read index received' (duration: 23.584863ms)","trace[1908514463] 'applied index is now lower than readState.Index' (duration: 180.195814ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:50:10.223725Z","caller":"traceutil/trace.go:171","msg":"trace[1194597808] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"199.069018ms","start":"2026-07-14T22:50:10.024649Z","end":"2026-07-14T22:50:10.223718Z","steps":["trace[1194597808] 'process raft request' (duration: 198.876378ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:10.223799Z","caller":"traceutil/trace.go:171","msg":"trace[1442716909] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"204.823557ms","start":"2026-07-14T22:50:10.018969Z","end":"2026-07-14T22:50:10.223792Z","steps":["trace[1442716909] 'process raft request' (duration: 204.452187ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:10.223941Z","caller":"traceutil/trace.go:171","msg":"trace[454455526] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"201.914438ms","start":"2026-07-14T22:50:10.02202Z","end":"2026-07-14T22:50:10.223935Z","steps":["trace[454455526] 'process raft request' (duration: 201.458678ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:10.223994Z","caller":"traceutil/trace.go:171","msg":"trace[1500561782] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"198.815679ms","start":"2026-07-14T22:50:10.025166Z","end":"2026-07-14T22:50:10.223982Z","steps":["trace[1500561782] 'process raft request' (duration: 198.399229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:10.224116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.282227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-07-14T22:50:10.224191Z","caller":"traceutil/trace.go:171","msg":"trace[1785325342] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:339; }","duration":"204.345307ms","start":"2026-07-14T22:50:10.019792Z","end":"2026-07-14T22:50:10.224137Z","steps":["trace[1785325342] 'agreement among raft nodes before linearized reading' (duration: 204.278087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:10.224219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.149631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:50:10.224257Z","caller":"traceutil/trace.go:171","msg":"trace[2086085929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:339; }","duration":"191.218731ms","start":"2026-07-14T22:50:10.033028Z","end":"2026-07-14T22:50:10.224247Z","steps":["trace[2086085929] 'agreement among raft nodes before linearized reading' (duration: 191.158191ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:10.607245Z","caller":"traceutil/trace.go:171","msg":"trace[1035810906] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"104.153468ms","start":"2026-07-14T22:50:10.503059Z","end":"2026-07-14T22:50:10.607212Z","steps":["trace[1035810906] 'process raft request' (duration: 40.626517ms)","trace[1035810906] 'compare' (duration: 63.237421ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:50:16.99984Z","caller":"traceutil/trace.go:171","msg":"trace[1061843340] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"103.752149ms","start":"2026-07-14T22:50:16.895998Z","end":"2026-07-14T22:50:16.99975Z","steps":["trace[1061843340] 'process raft request' (duration: 103.582429ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:38.841527Z","caller":"traceutil/trace.go:171","msg":"trace[1743677906] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"601.643016ms","start":"2026-07-14T22:50:38.239858Z","end":"2026-07-14T22:50:38.841501Z","steps":["trace[1743677906] 'process raft request' (duration: 597.051253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:38.841697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:38.239831Z","time spent":"601.779858ms","remote":"127.0.0.1:35374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:50:38.843993Z","caller":"traceutil/trace.go:171","msg":"trace[855046288] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:557; }","duration":"285.951953ms","start":"2026-07-14T22:50:38.558013Z","end":"2026-07-14T22:50:38.843965Z","steps":["trace[855046288] 'read index received' (duration: 278.798049ms)","trace[855046288] 'applied index is now lower than readState.Index' (duration: 7.152824ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:50:38.844092Z","caller":"traceutil/trace.go:171","msg":"trace[1688437265] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"564.544712ms","start":"2026-07-14T22:50:38.279522Z","end":"2026-07-14T22:50:38.844066Z","steps":["trace[1688437265] 'process raft request' (duration: 564.233533ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:38.844126Z","caller":"traceutil/trace.go:171","msg":"trace[760625882] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"543.850955ms","start":"2026-07-14T22:50:38.300261Z","end":"2026-07-14T22:50:38.844112Z","steps":["trace[760625882] 'process raft request' (duration: 543.643125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:38.844286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:38.300224Z","time spent":"543.968705ms","remote":"127.0.0.1:35374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:50:38.844333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.310726ms","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-14T22:50:38.84439Z","caller":"traceutil/trace.go:171","msg":"trace[131203279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:536; }","duration":"286.415367ms","start":"2026-07-14T22:50:38.557962Z","end":"2026-07-14T22:50:38.844378Z","steps":["trace[131203279] 'agreement among raft nodes before linearized reading' (duration: 286.23973ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:38.844374Z","caller":"traceutil/trace.go:171","msg":"trace[580978794] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"576.650112ms","start":"2026-07-14T22:50:38.26766Z","end":"2026-07-14T22:50:38.84431Z","steps":["trace[580978794] 'process raft request' (duration: 575.77409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:38.844544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:38.267639Z","time spent":"576.812198ms","remote":"127.0.0.1:35374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:50:38.844707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:38.279505Z","time spent":"564.655843ms","remote":"127.0.0.1:35374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:50:45.193096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.654094ms","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-14T22:50:45.19326Z","caller":"traceutil/trace.go:171","msg":"trace[656292338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"298.893345ms","start":"2026-07-14T22:50:44.894338Z","end":"2026-07-14T22:50:45.193232Z","steps":["trace[656292338] 'range keys from in-memory index tree' (duration: 298.513222ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:45.594732Z","caller":"traceutil/trace.go:171","msg":"trace[429551745] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"683.399999ms","start":"2026-07-14T22:50:44.911294Z","end":"2026-07-14T22:50:45.594694Z","steps":["trace[429551745] 'process raft request' (duration: 683.160148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:45.594964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:44.911266Z","time spent":"683.595055ms","remote":"127.0.0.1:35814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:50:46.311926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.453369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:50:46.312198Z","caller":"traceutil/trace.go:171","msg":"trace[573984101] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:621; }","duration":"1.195680945s","start":"2026-07-14T22:50:45.116495Z","end":"2026-07-14T22:50:46.312176Z","steps":["trace[573984101] 'read index received' (duration: 477.959391ms)","trace[573984101] 'applied index is now lower than readState.Index' (duration: 717.720234ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:50:46.312316Z","caller":"traceutil/trace.go:171","msg":"trace[1220586429] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.106775757s","start":"2026-07-14T22:50:45.20553Z","end":"2026-07-14T22:50:46.312305Z","steps":["trace[1220586429] 'process raft request' (duration: 1.106535277s)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:46.312349Z","caller":"traceutil/trace.go:171","msg":"trace[1296125366] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.333449763s","start":"2026-07-14T22:50:44.978863Z","end":"2026-07-14T22:50:46.312312Z","steps":["trace[1296125366] 'process raft request' (duration: 689.489921ms)","trace[1296125366] 'compare' (duration: 642.6427ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:50:46.31239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:45.205505Z","time spent":"1.106846253s","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:50:46.3125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:44.978842Z","time spent":"1.333575603s","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:50:46.31261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140342959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-14T22:50:46.312681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.03321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:50:46.312715Z","caller":"traceutil/trace.go:171","msg":"trace[491118004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"1.140501982s","start":"2026-07-14T22:50:45.172181Z","end":"2026-07-14T22:50:46.312683Z","steps":["trace[491118004] 'agreement among raft nodes before linearized reading' (duration: 1.140311496s)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:46.312735Z","caller":"traceutil/trace.go:171","msg":"trace[1347215084] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:597; }","duration":"702.082444ms","start":"2026-07-14T22:50:45.610631Z","end":"2026-07-14T22:50:46.312714Z","steps":["trace[1347215084] 'agreement among raft nodes before linearized reading' (duration: 701.997667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:46.312776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:45.610547Z","time spent":"702.220387ms","remote":"127.0.0.1:35526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-14T22:50:46.312786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:45.172164Z","time spent":"1.140603401s","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T22:50:46.31284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19634449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-czgjc\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-14T22:50:46.312862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090561188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T22:50:46.312873Z","caller":"traceutil/trace.go:171","msg":"trace[45624487] range","detail":"{range_begin:/registry/pods/kube-system/cilium-czgjc; range_end:; response_count:1; response_revision:597; }","duration":"1.196431158s","start":"2026-07-14T22:50:45.11643Z","end":"2026-07-14T22:50:46.312861Z","steps":["trace[45624487] 'agreement among raft nodes before linearized reading' (duration: 1.196311078s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:46.312901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:45.116409Z","time spent":"1.196487663s","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-czgjc\" "} {"level":"info","ts":"2026-07-14T22:50:46.312903Z","caller":"traceutil/trace.go:171","msg":"trace[1812490162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"1.090647065s","start":"2026-07-14T22:50:45.222243Z","end":"2026-07-14T22:50:46.31289Z","steps":["trace[1812490162] 'agreement among raft nodes before linearized reading' (duration: 1.090459989s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:46.312931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:45.222141Z","time spent":"1.090784037s","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T22:50:46.312975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.795732ms","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-14T22:50:46.313Z","caller":"traceutil/trace.go:171","msg":"trace[2037122860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"391.879989ms","start":"2026-07-14T22:50:45.921112Z","end":"2026-07-14T22:50:46.312992Z","steps":["trace[2037122860] 'agreement among raft nodes before linearized reading' (duration: 391.801842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:46.313024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:45.921047Z","time spent":"391.969237ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T22:50:46.313103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104581278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:50:46.313164Z","caller":"traceutil/trace.go:171","msg":"trace[1137743094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"1.104682757s","start":"2026-07-14T22:50:45.208464Z","end":"2026-07-14T22:50:46.313147Z","steps":["trace[1137743094] 'agreement among raft nodes before linearized reading' (duration: 1.10460414s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:46.313314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:45.20843Z","time spent":"1.104771075s","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T22:50:46.828192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844754581762116,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T22:50:47.673351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.61296ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:50:47.673464Z","caller":"traceutil/trace.go:171","msg":"trace[904631060] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:598; }","duration":"258.736641ms","start":"2026-07-14T22:50:47.414711Z","end":"2026-07-14T22:50:47.673447Z","steps":["trace[904631060] 'range keys from in-memory index tree' (duration: 258.597009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.673415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.844913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:50:47.673608Z","caller":"traceutil/trace.go:171","msg":"trace[620704249] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.354712561s","start":"2026-07-14T22:50:46.318887Z","end":"2026-07-14T22:50:47.6736Z","steps":["trace[620704249] 'process raft request' (duration: 1.354664907s)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:47.673629Z","caller":"traceutil/trace.go:171","msg":"trace[842085877] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.35612919s","start":"2026-07-14T22:50:46.317487Z","end":"2026-07-14T22:50:47.673616Z","steps":["trace[842085877] 'process raft request' (duration: 768.041227ms)","trace[842085877] 'compare' (duration: 587.733794ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:50:47.673644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.318876Z","time spent":"1.354749754s","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:50:47.673686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.317479Z","time spent":"1.356174914s","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:50:47.754071Z","caller":"traceutil/trace.go:171","msg":"trace[414312164] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.428771912s","start":"2026-07-14T22:50:46.325267Z","end":"2026-07-14T22:50:47.754039Z","steps":["trace[414312164] 'process raft request' (duration: 1.428588777s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.75449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.325256Z","time spent":"1.429017222s","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:50:47.757557Z","caller":"traceutil/trace.go:171","msg":"trace[648913304] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.002071304s","start":"2026-07-14T22:50:46.755321Z","end":"2026-07-14T22:50:47.757392Z","steps":["trace[648913304] 'process raft request' (duration: 1.001381537s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.757799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.755286Z","time spent":"1.002387781s","remote":"127.0.0.1:35420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:50:47.771892Z","caller":"traceutil/trace.go:171","msg":"trace[969799917] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:625; }","duration":"1.443773638s","start":"2026-07-14T22:50:46.328098Z","end":"2026-07-14T22:50:47.771872Z","steps":["trace[969799917] 'read index received' (duration: 757.503948ms)","trace[969799917] 'applied index is now lower than readState.Index' (duration: 686.26886ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:50:47.771993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443873416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:50:47.772026Z","caller":"traceutil/trace.go:171","msg":"trace[2017114682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"1.443946112s","start":"2026-07-14T22:50:46.328069Z","end":"2026-07-14T22:50:47.772015Z","steps":["trace[2017114682] 'agreement among raft nodes before linearized reading' (duration: 1.443877767s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.772068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.328062Z","time spent":"1.443999497s","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T22:50:49.584982Z","caller":"traceutil/trace.go:171","msg":"trace[115587801] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"251.171505ms","start":"2026-07-14T22:50:49.333788Z","end":"2026-07-14T22:50:49.58496Z","steps":["trace[115587801] 'process raft request' (duration: 250.98714ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:03.693348Z","caller":"traceutil/trace.go:171","msg":"trace[1356400582] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"473.008054ms","start":"2026-07-14T22:51:03.220313Z","end":"2026-07-14T22:51:03.693321Z","steps":["trace[1356400582] 'process raft request' (duration: 472.859634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:03.693508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:03.220288Z","time spent":"473.145574ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:51:03.724351Z","caller":"traceutil/trace.go:171","msg":"trace[1945128488] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"487.092973ms","start":"2026-07-14T22:51:03.237236Z","end":"2026-07-14T22:51:03.724329Z","steps":["trace[1945128488] 'process raft request' (duration: 486.399677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:03.724504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:03.237215Z","time spent":"487.206102ms","remote":"127.0.0.1:35526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:51:17.324153Z","caller":"traceutil/trace.go:171","msg":"trace[1370800914] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"409.77103ms","start":"2026-07-14T22:51:16.914238Z","end":"2026-07-14T22:51:17.324009Z","steps":["trace[1370800914] 'process raft request' (duration: 394.018021ms)","trace[1370800914] 'compare' (duration: 15.615271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:51:17.324325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:16.914233Z","time spent":"410.010133ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:51:17.327764Z","caller":"traceutil/trace.go:171","msg":"trace[679657255] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"408.037687ms","start":"2026-07-14T22:51:16.9197Z","end":"2026-07-14T22:51:17.327737Z","steps":["trace[679657255] 'process raft request' (duration: 407.20044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:17.327904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:16.919693Z","time spent":"408.146594ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:51:34.243832Z","caller":"traceutil/trace.go:171","msg":"trace[372216893] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"364.693982ms","start":"2026-07-14T22:51:33.879114Z","end":"2026-07-14T22:51:34.243808Z","steps":["trace[372216893] 'read index received' (duration: 364.475552ms)","trace[372216893] 'applied index is now lower than readState.Index' (duration: 217.59µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:51:34.243966Z","caller":"traceutil/trace.go:171","msg":"trace[2083159624] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"364.86132ms","start":"2026-07-14T22:51:33.87908Z","end":"2026-07-14T22:51:34.243941Z","steps":["trace[2083159624] 'process raft request' (duration: 364.576457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:34.244056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.922602ms","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-14T22:51:34.244092Z","caller":"traceutil/trace.go:171","msg":"trace[1109827076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1171; }","duration":"365.005437ms","start":"2026-07-14T22:51:33.879076Z","end":"2026-07-14T22:51:34.244082Z","steps":["trace[1109827076] 'agreement among raft nodes before linearized reading' (duration: 364.85093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:34.244112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:33.879055Z","time spent":"364.968805ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:51:34.244216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.359143ms","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-14T22:51:34.244247Z","caller":"traceutil/trace.go:171","msg":"trace[470640700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1171; }","duration":"362.402135ms","start":"2026-07-14T22:51:33.881836Z","end":"2026-07-14T22:51:34.244238Z","steps":["trace[470640700] 'agreement among raft nodes before linearized reading' (duration: 362.322971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:34.244275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:33.881823Z","time spent":"362.444877ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T22:51:34.24413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:33.879056Z","time spent":"365.06694ms","remote":"127.0.0.1:35546","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-14T22:51:56.330895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.368384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T22:51:56.331074Z","caller":"traceutil/trace.go:171","msg":"trace[46314407] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1324; }","duration":"189.601912ms","start":"2026-07-14T22:51:56.14145Z","end":"2026-07-14T22:51:56.331052Z","steps":["trace[46314407] 'count revisions from in-memory index tree' (duration: 189.265059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:56.33109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.809191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:51:56.331235Z","caller":"traceutil/trace.go:171","msg":"trace[97897073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"123.975717ms","start":"2026-07-14T22:51:56.207246Z","end":"2026-07-14T22:51:56.331221Z","steps":["trace[97897073] 'range keys from in-memory index tree' (duration: 123.742379ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:00.424276Z","caller":"traceutil/trace.go:171","msg":"trace[857514477] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"117.948586ms","start":"2026-07-14T22:52:00.306302Z","end":"2026-07-14T22:52:00.42425Z","steps":["trace[857514477] 'process raft request' (duration: 110.53839ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:00.605316Z","caller":"traceutil/trace.go:171","msg":"trace[478571804] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"135.281906ms","start":"2026-07-14T22:52:00.470014Z","end":"2026-07-14T22:52:00.605296Z","steps":["trace[478571804] 'process raft request' (duration: 135.165552ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:00.60537Z","caller":"traceutil/trace.go:171","msg":"trace[1425562001] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"135.059388ms","start":"2026-07-14T22:52:00.470212Z","end":"2026-07-14T22:52:00.605271Z","steps":["trace[1425562001] 'process raft request' (duration: 135.021576ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:00.605447Z","caller":"traceutil/trace.go:171","msg":"trace[319732557] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"176.49296ms","start":"2026-07-14T22:52:00.428932Z","end":"2026-07-14T22:52:00.605424Z","steps":["trace[319732557] 'process raft request' (duration: 98.262036ms)","trace[319732557] 'compare' (duration: 77.756058ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:52:00.812485Z","caller":"traceutil/trace.go:171","msg":"trace[1991838994] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"136.460146ms","start":"2026-07-14T22:52:00.675985Z","end":"2026-07-14T22:52:00.812445Z","steps":["trace[1991838994] 'process raft request' (duration: 71.820842ms)","trace[1991838994] 'compare' (duration: 64.125696ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:52:00.812551Z","caller":"traceutil/trace.go:171","msg":"trace[1802883710] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1420; }","duration":"136.297521ms","start":"2026-07-14T22:52:00.676215Z","end":"2026-07-14T22:52:00.812512Z","steps":["trace[1802883710] 'read index received' (duration: 2.418973ms)","trace[1802883710] 'applied index is now lower than readState.Index' (duration: 133.875167ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:52:00.812554Z","caller":"traceutil/trace.go:171","msg":"trace[1003810682] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"104.46052ms","start":"2026-07-14T22:52:00.708068Z","end":"2026-07-14T22:52:00.812529Z","steps":["trace[1003810682] 'process raft request' (duration: 104.359577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:00.812905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.678004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:116816"} {"level":"info","ts":"2026-07-14T22:52:00.812947Z","caller":"traceutil/trace.go:171","msg":"trace[1321937028] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1374; }","duration":"136.771397ms","start":"2026-07-14T22:52:00.676164Z","end":"2026-07-14T22:52:00.812936Z","steps":["trace[1321937028] 'agreement among raft nodes before linearized reading' (duration: 136.526299ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:03.272545Z","caller":"traceutil/trace.go:171","msg":"trace[1125298125] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"150.834555ms","start":"2026-07-14T22:52:03.121673Z","end":"2026-07-14T22:52:03.272508Z","steps":["trace[1125298125] 'process raft request' (duration: 150.144682ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:03.272976Z","caller":"traceutil/trace.go:171","msg":"trace[1564167994] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"109.604684ms","start":"2026-07-14T22:52:03.163342Z","end":"2026-07-14T22:52:03.272947Z","steps":["trace[1564167994] 'process raft request' (duration: 109.255012ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:24.462347Z","caller":"traceutil/trace.go:171","msg":"trace[941765067] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"172.919737ms","start":"2026-07-14T22:52:24.289397Z","end":"2026-07-14T22:52:24.462316Z","steps":["trace[941765067] 'process raft request' (duration: 172.738292ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:32.37167Z","caller":"traceutil/trace.go:171","msg":"trace[2078838869] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"512.630664ms","start":"2026-07-14T22:52:31.859004Z","end":"2026-07-14T22:52:32.371635Z","steps":["trace[2078838869] 'process raft request' (duration: 512.353167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:32.371886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:31.858989Z","time spent":"512.791519ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:32.376169Z","caller":"traceutil/trace.go:171","msg":"trace[53922384] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1953; }","duration":"296.423543ms","start":"2026-07-14T22:52:32.079719Z","end":"2026-07-14T22:52:32.376143Z","steps":["trace[53922384] 'read index received' (duration: 290.985274ms)","trace[53922384] 'applied index is now lower than readState.Index' (duration: 5.437249ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:52:32.37631Z","caller":"traceutil/trace.go:171","msg":"trace[1842421799] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"392.177531ms","start":"2026-07-14T22:52:31.984108Z","end":"2026-07-14T22:52:32.376285Z","steps":["trace[1842421799] 'process raft request' (duration: 391.854033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:32.376396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.66075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c63b3a9f-2a5e-4ad2-a35a-1a0d5c5dedfc\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-07-14T22:52:32.376428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:31.984075Z","time spent":"392.285834ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:32.376444Z","caller":"traceutil/trace.go:171","msg":"trace[209749501] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c63b3a9f-2a5e-4ad2-a35a-1a0d5c5dedfc; range_end:; response_count:1; response_revision:1876; }","duration":"296.784623ms","start":"2026-07-14T22:52:32.079646Z","end":"2026-07-14T22:52:32.376431Z","steps":["trace[209749501] 'agreement among raft nodes before linearized reading' (duration: 296.66551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:32.376577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.349016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:52:32.376722Z","caller":"traceutil/trace.go:171","msg":"trace[670705919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1876; }","duration":"165.50422ms","start":"2026-07-14T22:52:32.211133Z","end":"2026-07-14T22:52:32.376637Z","steps":["trace[670705919] 'agreement among raft nodes before linearized reading' (duration: 165.327186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:32.377021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.83584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-14T22:52:32.377069Z","caller":"traceutil/trace.go:171","msg":"trace[39416761] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1876; }","duration":"142.934722ms","start":"2026-07-14T22:52:32.234122Z","end":"2026-07-14T22:52:32.377057Z","steps":["trace[39416761] 'agreement among raft nodes before linearized reading' (duration: 142.768048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.358284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.286503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:52:33.358646Z","caller":"traceutil/trace.go:171","msg":"trace[31747505] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1955; }","duration":"723.663964ms","start":"2026-07-14T22:52:32.634965Z","end":"2026-07-14T22:52:33.358629Z","steps":["trace[31747505] 'read index received' (duration: 48.168034ms)","trace[31747505] 'applied index is now lower than readState.Index' (duration: 675.4948ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:52:33.358747Z","caller":"traceutil/trace.go:171","msg":"trace[1079188510] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"867.607551ms","start":"2026-07-14T22:52:32.491129Z","end":"2026-07-14T22:52:33.358737Z","steps":["trace[1079188510] 'process raft request' (duration: 867.330124ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:33.358777Z","caller":"traceutil/trace.go:171","msg":"trace[1407288069] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"846.723236ms","start":"2026-07-14T22:52:32.512042Z","end":"2026-07-14T22:52:33.358765Z","steps":["trace[1407288069] 'process raft request' (duration: 846.547262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.358889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:32.491099Z","time spent":"867.694794ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:33.358659Z","caller":"traceutil/trace.go:171","msg":"trace[2100139336] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"971.903763ms","start":"2026-07-14T22:52:32.386722Z","end":"2026-07-14T22:52:33.358626Z","steps":["trace[2100139336] 'process raft request' (duration: 186.190279ms)","trace[2100139336] 'compare' (duration: 785.17927ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:52:33.359317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.343731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-14T22:52:33.359345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:32.386699Z","time spent":"972.613261ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:33.359369Z","caller":"traceutil/trace.go:171","msg":"trace[1822282535] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:1879; }","duration":"724.432343ms","start":"2026-07-14T22:52:32.634921Z","end":"2026-07-14T22:52:33.359353Z","steps":["trace[1822282535] 'agreement among raft nodes before linearized reading' (duration: 724.263649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.359404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:32.634898Z","time spent":"724.498385ms","remote":"127.0.0.1:35360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"warn","ts":"2026-07-14T22:52:33.359013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:32.511999Z","time spent":"846.911671ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:52:33.685238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.503745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:52:33.685339Z","caller":"traceutil/trace.go:171","msg":"trace[712778000] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"326.589298ms","start":"2026-07-14T22:52:33.358733Z","end":"2026-07-14T22:52:33.685323Z","steps":["trace[712778000] 'read index received' (duration: 147.868427ms)","trace[712778000] 'applied index is now lower than readState.Index' (duration: 178.71949ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:52:33.685536Z","caller":"traceutil/trace.go:171","msg":"trace[1093694232] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"676.897837ms","start":"2026-07-14T22:52:33.008625Z","end":"2026-07-14T22:52:33.685523Z","steps":["trace[1093694232] 'process raft request' (duration: 498.026963ms)","trace[1093694232] 'compare' (duration: 178.391041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:52:33.685624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.008605Z","time spent":"676.969299ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:52:33.686093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.847772ms","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-14T22:52:33.686136Z","caller":"traceutil/trace.go:171","msg":"trace[1920723387] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1880; }","duration":"642.918514ms","start":"2026-07-14T22:52:33.043206Z","end":"2026-07-14T22:52:33.686124Z","steps":["trace[1920723387] 'agreement among raft nodes before linearized reading' (duration: 642.76899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.686168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.043186Z","time spent":"642.974745ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T22:52:33.686166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.720481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T22:52:33.686647Z","caller":"traceutil/trace.go:171","msg":"trace[1791367719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1880; }","duration":"638.259216ms","start":"2026-07-14T22:52:33.048366Z","end":"2026-07-14T22:52:33.686625Z","steps":["trace[1791367719] 'agreement among raft nodes before linearized reading' (duration: 637.607799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.686165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.41393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T22:52:33.686743Z","caller":"traceutil/trace.go:171","msg":"trace[211320815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1880; }","duration":"628.031305ms","start":"2026-07-14T22:52:33.058698Z","end":"2026-07-14T22:52:33.686729Z","steps":["trace[211320815] 'agreement among raft nodes before linearized reading' (duration: 627.306807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.686859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.058686Z","time spent":"628.158258ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T22:52:33.686177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.438013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-14T22:52:33.687951Z","caller":"traceutil/trace.go:171","msg":"trace[263522004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1880; }","duration":"622.245989ms","start":"2026-07-14T22:52:33.065681Z","end":"2026-07-14T22:52:33.687927Z","steps":["trace[263522004] 'agreement among raft nodes before linearized reading' (duration: 620.299209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.68803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.065664Z","time spent":"622.344351ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T22:52:33.686472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.078384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T22:52:33.68834Z","caller":"traceutil/trace.go:171","msg":"trace[884240909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1880; }","duration":"621.91467ms","start":"2026-07-14T22:52:33.066374Z","end":"2026-07-14T22:52:33.688289Z","steps":["trace[884240909] 'agreement among raft nodes before linearized reading' (duration: 620.022942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.688554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.066369Z","time spent":"622.129505ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T22:52:33.686744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.048335Z","time spent":"638.393938ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T22:52:33.729584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.360445Z","time spent":"369.132128ms","remote":"127.0.0.1:35360","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-14T22:52:33.72989Z","caller":"traceutil/trace.go:171","msg":"trace[1239418207] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"365.333991ms","start":"2026-07-14T22:52:33.364457Z","end":"2026-07-14T22:52:33.729791Z","steps":["trace[1239418207] 'process raft request' (duration: 365.052394ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:33.729947Z","caller":"traceutil/trace.go:171","msg":"trace[1756051741] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"365.090736ms","start":"2026-07-14T22:52:33.364792Z","end":"2026-07-14T22:52:33.729883Z","steps":["trace[1756051741] 'process raft request' (duration: 364.85178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.730125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.36444Z","time spent":"365.583008ms","remote":"127.0.0.1:35556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:52:33.730129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.364774Z","time spent":"365.25706ms","remote":"127.0.0.1:35462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:33.730391Z","caller":"traceutil/trace.go:171","msg":"trace[849442975] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"365.063224ms","start":"2026-07-14T22:52:33.364957Z","end":"2026-07-14T22:52:33.73002Z","steps":["trace[849442975] 'process raft request' (duration: 364.805388ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:33.730431Z","caller":"traceutil/trace.go:171","msg":"trace[14074158] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"311.017091ms","start":"2026-07-14T22:52:33.419394Z","end":"2026-07-14T22:52:33.730412Z","steps":["trace[14074158] 'process raft request' (duration: 310.502698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.730472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.593741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c63b3a9f-2a5e-4ad2-a35a-1a0d5c5dedfc\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-07-14T22:52:33.730501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.419356Z","time spent":"311.107224ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:33.730533Z","caller":"traceutil/trace.go:171","msg":"trace[1548988649] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c63b3a9f-2a5e-4ad2-a35a-1a0d5c5dedfc; range_end:; response_count:1; response_revision:1885; }","duration":"347.711724ms","start":"2026-07-14T22:52:33.382805Z","end":"2026-07-14T22:52:33.730517Z","steps":["trace[1548988649] 'agreement among raft nodes before linearized reading' (duration: 347.521949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.730576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.382775Z","time spent":"347.793326ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c63b3a9f-2a5e-4ad2-a35a-1a0d5c5dedfc\" "} {"level":"warn","ts":"2026-07-14T22:52:33.730653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.621461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-14T22:52:33.730705Z","caller":"traceutil/trace.go:171","msg":"trace[895147676] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1885; }","duration":"345.689202ms","start":"2026-07-14T22:52:33.385003Z","end":"2026-07-14T22:52:33.730693Z","steps":["trace[895147676] 'agreement among raft nodes before linearized reading' (duration: 345.536368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.730742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.384994Z","time spent":"345.740263ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-14T22:52:33.730707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.364952Z","time spent":"365.497206ms","remote":"127.0.0.1:35556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:33.730905Z","caller":"traceutil/trace.go:171","msg":"trace[695750102] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"364.527511ms","start":"2026-07-14T22:52:33.365554Z","end":"2026-07-14T22:52:33.730082Z","steps":["trace[695750102] 'process raft request' (duration: 364.280334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:33.730976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.365547Z","time spent":"365.397983ms","remote":"127.0.0.1:35462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:52:34.622624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.804983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:52:34.622842Z","caller":"traceutil/trace.go:171","msg":"trace[92586053] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"929.650677ms","start":"2026-07-14T22:52:33.693178Z","end":"2026-07-14T22:52:34.622829Z","steps":["trace[92586053] 'process raft request' (duration: 929.605016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:34.622891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.693168Z","time spent":"929.701508ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:34.622906Z","caller":"traceutil/trace.go:171","msg":"trace[1674356209] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"931.230038ms","start":"2026-07-14T22:52:33.691658Z","end":"2026-07-14T22:52:34.622888Z","steps":["trace[1674356209] 'process raft request' (duration: 812.078276ms)","trace[1674356209] 'compare' (duration: 118.623858ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:52:34.623001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.69164Z","time spent":"931.312689ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:34.623033Z","caller":"traceutil/trace.go:171","msg":"trace[372863149] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"930.434487ms","start":"2026-07-14T22:52:33.692592Z","end":"2026-07-14T22:52:34.623026Z","steps":["trace[372863149] 'process raft request' (duration: 930.15439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:34.623066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.692561Z","time spent":"930.486149ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:34.623013Z","caller":"traceutil/trace.go:171","msg":"trace[821084637] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"931.241628ms","start":"2026-07-14T22:52:33.691765Z","end":"2026-07-14T22:52:34.623006Z","steps":["trace[821084637] 'process raft request' (duration: 930.93099ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:34.623179Z","caller":"traceutil/trace.go:171","msg":"trace[433584708] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"929.949905ms","start":"2026-07-14T22:52:33.693223Z","end":"2026-07-14T22:52:34.623173Z","steps":["trace[433584708] 'process raft request' (duration: 929.582455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:34.6232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.691742Z","time spent":"931.428452ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:34.62333Z","caller":"traceutil/trace.go:171","msg":"trace[190002998] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"891.98595ms","start":"2026-07-14T22:52:33.731339Z","end":"2026-07-14T22:52:34.623325Z","steps":["trace[190002998] 'process raft request' (duration: 891.804295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:34.623217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.69321Z","time spent":"929.988645ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:52:34.623382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.731316Z","time spent":"892.030941ms","remote":"127.0.0.1:35360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:34.62371Z","caller":"traceutil/trace.go:171","msg":"trace[701197494] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"888.989494ms","start":"2026-07-14T22:52:33.734711Z","end":"2026-07-14T22:52:34.6237Z","steps":["trace[701197494] 'process raft request' (duration: 888.588654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:34.623757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:33.734687Z","time spent":"889.045825ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:34.623821Z","caller":"traceutil/trace.go:171","msg":"trace[1512468529] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1965; }","duration":"476.491155ms","start":"2026-07-14T22:52:34.147183Z","end":"2026-07-14T22:52:34.623674Z","steps":["trace[1512468529] 'read index received' (duration: 356.568924ms)","trace[1512468529] 'applied index is now lower than readState.Index' (duration: 119.920871ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:52:34.623912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.726461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:52:34.624089Z","caller":"traceutil/trace.go:171","msg":"trace[328673154] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1892; }","duration":"476.950857ms","start":"2026-07-14T22:52:34.147129Z","end":"2026-07-14T22:52:34.624079Z","steps":["trace[328673154] 'agreement among raft nodes before linearized reading' (duration: 476.69459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:34.624182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.570025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:52:34.624247Z","caller":"traceutil/trace.go:171","msg":"trace[1005053208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1892; }","duration":"416.659788ms","start":"2026-07-14T22:52:34.20758Z","end":"2026-07-14T22:52:34.62424Z","steps":["trace[1005053208] 'agreement among raft nodes before linearized reading' (duration: 416.583086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:34.624265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:34.20756Z","time spent":"416.699998ms","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T22:52:34.624139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:34.147102Z","time spent":"477.006128ms","remote":"127.0.0.1:56018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-14T22:52:45.907849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.023447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-nhq85\" ","response":"range_response_count:1 size:6144"} {"level":"info","ts":"2026-07-14T22:52:45.907952Z","caller":"traceutil/trace.go:171","msg":"trace[816606222] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"156.104955ms","start":"2026-07-14T22:52:45.751801Z","end":"2026-07-14T22:52:45.907905Z","steps":["trace[816606222] 'process raft request' (duration: 149.342258ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:45.907978Z","caller":"traceutil/trace.go:171","msg":"trace[1741489997] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-nhq85; range_end:; response_count:1; response_revision:1990; }","duration":"390.192881ms","start":"2026-07-14T22:52:45.517756Z","end":"2026-07-14T22:52:45.907949Z","steps":["trace[1741489997] 'range keys from in-memory index tree' (duration: 389.801011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:45.908054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:45.517732Z","time spent":"390.303183ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":6166,"request content":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-nhq85\" "} {"level":"info","ts":"2026-07-14T22:52:45.919537Z","caller":"traceutil/trace.go:171","msg":"trace[1622473043] linearizableReadLoop","detail":"{readStateIndex:2074; appliedIndex:2072; }","duration":"106.297015ms","start":"2026-07-14T22:52:45.813224Z","end":"2026-07-14T22:52:45.919521Z","steps":["trace[1622473043] 'read index received' (duration: 87.936361ms)","trace[1622473043] 'applied index is now lower than readState.Index' (duration: 18.359674ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:52:45.919583Z","caller":"traceutil/trace.go:171","msg":"trace[366068903] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"151.911348ms","start":"2026-07-14T22:52:45.76765Z","end":"2026-07-14T22:52:45.919562Z","steps":["trace[366068903] 'process raft request' (duration: 151.725163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:45.919743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.501889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-14T22:52:45.919807Z","caller":"traceutil/trace.go:171","msg":"trace[1824106275] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1992; }","duration":"106.616252ms","start":"2026-07-14T22:52:45.813173Z","end":"2026-07-14T22:52:45.91979Z","steps":["trace[1824106275] 'agreement among raft nodes before linearized reading' (duration: 106.417167ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:46.182832Z","caller":"traceutil/trace.go:171","msg":"trace[373401217] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"263.959534ms","start":"2026-07-14T22:52:45.91883Z","end":"2026-07-14T22:52:46.182789Z","steps":["trace[373401217] 'process raft request' (duration: 263.712839ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:46.336563Z","caller":"traceutil/trace.go:171","msg":"trace[1481670339] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"147.089531ms","start":"2026-07-14T22:52:46.18937Z","end":"2026-07-14T22:52:46.336459Z","steps":["trace[1481670339] 'process raft request' (duration: 134.546174ms)","trace[1481670339] 'compare' (duration: 12.22789ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:52:46.336925Z","caller":"traceutil/trace.go:171","msg":"trace[597053614] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"147.516351ms","start":"2026-07-14T22:52:46.189387Z","end":"2026-07-14T22:52:46.336904Z","steps":["trace[597053614] 'process raft request' (duration: 147.01356ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:46.337132Z","caller":"traceutil/trace.go:171","msg":"trace[359996056] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2075; }","duration":"132.713311ms","start":"2026-07-14T22:52:46.204361Z","end":"2026-07-14T22:52:46.337074Z","steps":["trace[359996056] 'read index received' (duration: 20.022658ms)","trace[359996056] 'applied index is now lower than readState.Index' (duration: 112.688243ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:52:46.33722Z","caller":"traceutil/trace.go:171","msg":"trace[609056404] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"146.940798ms","start":"2026-07-14T22:52:46.190262Z","end":"2026-07-14T22:52:46.337203Z","steps":["trace[609056404] 'process raft request' (duration: 146.552129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:46.337326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.947137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:52:46.337391Z","caller":"traceutil/trace.go:171","msg":"trace[1426435291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1997; }","duration":"133.045349ms","start":"2026-07-14T22:52:46.204325Z","end":"2026-07-14T22:52:46.33737Z","steps":["trace[1426435291] 'agreement among raft nodes before linearized reading' (duration: 132.915146ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:49.863116Z","caller":"traceutil/trace.go:171","msg":"trace[808561106] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"448.44717ms","start":"2026-07-14T22:52:49.414626Z","end":"2026-07-14T22:52:49.863073Z","steps":["trace[808561106] 'process raft request' (duration: 448.231146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:49.863332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:49.414585Z","time spent":"448.631755ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:52:50.192447Z","caller":"traceutil/trace.go:171","msg":"trace[1578675892] linearizableReadLoop","detail":"{readStateIndex:2101; appliedIndex:2099; }","duration":"623.544684ms","start":"2026-07-14T22:52:49.568876Z","end":"2026-07-14T22:52:50.19242Z","steps":["trace[1578675892] 'read index received' (duration: 294.019519ms)","trace[1578675892] 'applied index is now lower than readState.Index' (duration: 329.524215ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:52:50.192616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.710808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T22:52:50.192604Z","caller":"traceutil/trace.go:171","msg":"trace[1601546106] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"777.564907ms","start":"2026-07-14T22:52:49.415004Z","end":"2026-07-14T22:52:50.192569Z","steps":["trace[1601546106] 'process raft request' (duration: 777.236569ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:50.192657Z","caller":"traceutil/trace.go:171","msg":"trace[970437963] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2018; }","duration":"623.82172ms","start":"2026-07-14T22:52:49.568822Z","end":"2026-07-14T22:52:50.192644Z","steps":["trace[970437963] 'agreement among raft nodes before linearized reading' (duration: 623.727458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:52:50.192715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:49.568801Z","time spent":"623.885892ms","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":29,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-07-14T22:52:50.192771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:52:49.414965Z","time spent":"777.716759ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:52:50.19389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.556713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-14T22:52:50.19393Z","caller":"traceutil/trace.go:171","msg":"trace[450586019] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2020; }","duration":"245.642734ms","start":"2026-07-14T22:52:49.948277Z","end":"2026-07-14T22:52:50.19392Z","steps":["trace[450586019] 'agreement among raft nodes before linearized reading' (duration: 245.501181ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:50.19402Z","caller":"traceutil/trace.go:171","msg":"trace[358471050] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"273.441897ms","start":"2026-07-14T22:52:49.920557Z","end":"2026-07-14T22:52:50.193999Z","steps":["trace[358471050] 'process raft request' (duration: 273.09808ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:52:50.194113Z","caller":"traceutil/trace.go:171","msg":"trace[1293575678] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"264.935176ms","start":"2026-07-14T22:52:49.929167Z","end":"2026-07-14T22:52:50.194102Z","steps":["trace[1293575678] 'process raft request' (duration: 264.581999ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:53:14.519864Z","caller":"traceutil/trace.go:171","msg":"trace[593837608] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"245.174562ms","start":"2026-07-14T22:53:14.274657Z","end":"2026-07-14T22:53:14.519832Z","steps":["trace[593837608] 'process raft request' (duration: 245.004968ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:53:15.007833Z","caller":"traceutil/trace.go:171","msg":"trace[2008989107] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2241; }","duration":"651.394185ms","start":"2026-07-14T22:53:14.356423Z","end":"2026-07-14T22:53:15.007817Z","steps":["trace[2008989107] 'read index received' (duration: 163.222803ms)","trace[2008989107] 'applied index is now lower than readState.Index' (duration: 488.170762ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:53:15.007921Z","caller":"traceutil/trace.go:171","msg":"trace[657119787] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"721.784744ms","start":"2026-07-14T22:53:14.286129Z","end":"2026-07-14T22:53:15.007914Z","steps":["trace[657119787] 'process raft request' (duration: 721.617351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:15.008001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:14.286106Z","time spent":"721.841354ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:53:15.008272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.820653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-14T22:53:15.00791Z","caller":"traceutil/trace.go:171","msg":"trace[2034336020] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"725.645946ms","start":"2026-07-14T22:53:14.282244Z","end":"2026-07-14T22:53:15.00789Z","steps":["trace[2034336020] 'process raft request' (duration: 643.835651ms)","trace[2034336020] 'compare' (duration: 81.474379ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:53:15.00944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.65553ms","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-14T22:53:15.009531Z","caller":"traceutil/trace.go:171","msg":"trace[1598727275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2155; }","duration":"174.800443ms","start":"2026-07-14T22:53:14.834708Z","end":"2026-07-14T22:53:15.009508Z","steps":["trace[1598727275] 'agreement among raft nodes before linearized reading' (duration: 173.301455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:15.008462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.744328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-14T22:53:15.009574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:14.282219Z","time spent":"727.267967ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:53:15.009663Z","caller":"traceutil/trace.go:171","msg":"trace[375643492] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2155; }","duration":"119.98843ms","start":"2026-07-14T22:53:14.88966Z","end":"2026-07-14T22:53:15.009648Z","steps":["trace[375643492] 'agreement among raft nodes before linearized reading' (duration: 118.624685ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:53:15.009463Z","caller":"traceutil/trace.go:171","msg":"trace[2020273778] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2155; }","duration":"653.014646ms","start":"2026-07-14T22:53:14.356387Z","end":"2026-07-14T22:53:15.009402Z","steps":["trace[2020273778] 'agreement among raft nodes before linearized reading' (duration: 651.715961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:15.00999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:14.356371Z","time spent":"653.602817ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-14T22:53:15.660847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.071422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:53:15.661336Z","caller":"traceutil/trace.go:171","msg":"trace[341668860] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2244; }","duration":"599.32121ms","start":"2026-07-14T22:53:15.061997Z","end":"2026-07-14T22:53:15.661318Z","steps":["trace[341668860] 'read index received' (duration: 298.698887ms)","trace[341668860] 'applied index is now lower than readState.Index' (duration: 300.621123ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:53:15.66141Z","caller":"traceutil/trace.go:171","msg":"trace[140688624] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"648.234775ms","start":"2026-07-14T22:53:15.013143Z","end":"2026-07-14T22:53:15.661378Z","steps":["trace[140688624] 'process raft request' (duration: 647.808137ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:53:15.661426Z","caller":"traceutil/trace.go:171","msg":"trace[401974252] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"647.904028ms","start":"2026-07-14T22:53:15.013491Z","end":"2026-07-14T22:53:15.661395Z","steps":["trace[401974252] 'process raft request' (duration: 647.742045ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:53:15.661415Z","caller":"traceutil/trace.go:171","msg":"trace[64737078] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"647.567181ms","start":"2026-07-14T22:53:15.013838Z","end":"2026-07-14T22:53:15.661405Z","steps":["trace[64737078] 'process raft request' (duration: 647.436859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:15.661555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:15.013137Z","time spent":"648.340407ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:53:15.661565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:15.013486Z","time spent":"648.00531ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:53:15.661711Z","caller":"traceutil/trace.go:171","msg":"trace[2129883985] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"648.407618ms","start":"2026-07-14T22:53:15.013293Z","end":"2026-07-14T22:53:15.661701Z","steps":["trace[2129883985] 'process raft request' (duration: 647.715205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:15.661712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:15.013832Z","time spent":"647.753635ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:53:15.661754Z","caller":"traceutil/trace.go:171","msg":"trace[1804071970] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"648.840236ms","start":"2026-07-14T22:53:15.012904Z","end":"2026-07-14T22:53:15.661744Z","steps":["trace[1804071970] 'process raft request' (duration: 347.779765ms)","trace[1804071970] 'compare' (duration: 299.874128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:53:15.661774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:15.013287Z","time spent":"648.456089ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:53:15.661799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:15.012887Z","time spent":"648.888076ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:53:15.662322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.315209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:53:15.662373Z","caller":"traceutil/trace.go:171","msg":"trace[659850744] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2160; }","duration":"600.392691ms","start":"2026-07-14T22:53:15.061967Z","end":"2026-07-14T22:53:15.662359Z","steps":["trace[659850744] 'agreement among raft nodes before linearized reading' (duration: 600.242858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:15.662498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:15.061955Z","time spent":"600.462542ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-07-14T22:53:16.066493Z","caller":"traceutil/trace.go:171","msg":"trace[552593707] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"675.403701ms","start":"2026-07-14T22:53:15.391064Z","end":"2026-07-14T22:53:16.066468Z","steps":["trace[552593707] 'process raft request' (duration: 669.232415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:16.066633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:15.391035Z","time spent":"675.527294ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:53:16.066506Z","caller":"traceutil/trace.go:171","msg":"trace[1753709124] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2249; }","duration":"405.107751ms","start":"2026-07-14T22:53:15.661377Z","end":"2026-07-14T22:53:16.066485Z","steps":["trace[1753709124] 'read index received' (duration: 398.936505ms)","trace[1753709124] 'applied index is now lower than readState.Index' (duration: 6.170386ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:53:16.067421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.221709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c249d4b267c155\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-14T22:53:16.067501Z","caller":"traceutil/trace.go:171","msg":"trace[1743668094] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c249d4b267c155; range_end:; response_count:1; response_revision:2162; }","duration":"801.362372ms","start":"2026-07-14T22:53:15.266123Z","end":"2026-07-14T22:53:16.067486Z","steps":["trace[1743668094] 'agreement among raft nodes before linearized reading' (duration: 800.450595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:16.067539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:15.266103Z","time spent":"801.426963ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c249d4b267c155\" "} {"level":"info","ts":"2026-07-14T22:53:16.06758Z","caller":"traceutil/trace.go:171","msg":"trace[551511760] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"109.708956ms","start":"2026-07-14T22:53:15.957855Z","end":"2026-07-14T22:53:16.067564Z","steps":["trace[551511760] 'process raft request' (duration: 108.563705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:16.067683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.676923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T22:53:16.067701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.732257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-07-14T22:53:16.067849Z","caller":"traceutil/trace.go:171","msg":"trace[2094259634] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2162; }","duration":"529.93666ms","start":"2026-07-14T22:53:15.537894Z","end":"2026-07-14T22:53:16.06783Z","steps":["trace[2094259634] 'agreement among raft nodes before linearized reading' (duration: 528.669657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:16.067935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:15.537872Z","time spent":"530.049483ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12407,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-14T22:53:16.067957Z","caller":"traceutil/trace.go:171","msg":"trace[1477863582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2162; }","duration":"861.143742ms","start":"2026-07-14T22:53:15.206617Z","end":"2026-07-14T22:53:16.067761Z","steps":["trace[1477863582] 'agreement among raft nodes before linearized reading' (duration: 859.934299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:16.068061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:53:15.20659Z","time spent":"861.434957ms","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T22:54:02.538818Z","caller":"traceutil/trace.go:171","msg":"trace[1646141823] transaction","detail":"{read_only:false; response_revision:2518; number_of_response:1; }","duration":"298.305507ms","start":"2026-07-14T22:54:02.240488Z","end":"2026-07-14T22:54:02.538793Z","steps":["trace[1646141823] 'process raft request' (duration: 298.048133ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:54:03.331871Z","caller":"traceutil/trace.go:171","msg":"trace[196794990] transaction","detail":"{read_only:false; response_revision:2522; number_of_response:1; }","duration":"164.17278ms","start":"2026-07-14T22:54:03.167674Z","end":"2026-07-14T22:54:03.331846Z","steps":["trace[196794990] 'process raft request' (duration: 163.777924ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:54:03.332489Z","caller":"traceutil/trace.go:171","msg":"trace[1586686889] linearizableReadLoop","detail":"{readStateIndex:2623; appliedIndex:2621; }","duration":"136.562479ms","start":"2026-07-14T22:54:03.195909Z","end":"2026-07-14T22:54:03.332472Z","steps":["trace[1586686889] 'read index received' (duration: 135.788398ms)","trace[1586686889] 'applied index is now lower than readState.Index' (duration: 773.141µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:54:03.332643Z","caller":"traceutil/trace.go:171","msg":"trace[1323905999] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"159.546219ms","start":"2026-07-14T22:54:03.173085Z","end":"2026-07-14T22:54:03.332631Z","steps":["trace[1323905999] 'process raft request' (duration: 159.308645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:03.332811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.065255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:54:03.332882Z","caller":"traceutil/trace.go:171","msg":"trace[961480457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2523; }","duration":"129.167066ms","start":"2026-07-14T22:54:03.203703Z","end":"2026-07-14T22:54:03.33287Z","steps":["trace[961480457] 'agreement among raft nodes before linearized reading' (duration: 129.068125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:03.333047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.129828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:54:03.333088Z","caller":"traceutil/trace.go:171","msg":"trace[457406863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2523; }","duration":"137.194299ms","start":"2026-07-14T22:54:03.195882Z","end":"2026-07-14T22:54:03.333076Z","steps":["trace[457406863] 'agreement among raft nodes before linearized reading' (duration: 137.131558ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:54:35.607512Z","caller":"traceutil/trace.go:171","msg":"trace[534448046] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"144.239691ms","start":"2026-07-14T22:54:35.463248Z","end":"2026-07-14T22:54:35.607488Z","steps":["trace[534448046] 'process raft request' (duration: 144.048468ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:54:35.619229Z","caller":"traceutil/trace.go:171","msg":"trace[349772619] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"153.889535ms","start":"2026-07-14T22:54:35.465328Z","end":"2026-07-14T22:54:35.619217Z","steps":["trace[349772619] 'process raft request' (duration: 153.829854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:36.31047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.080575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:54:36.310562Z","caller":"traceutil/trace.go:171","msg":"trace[1403863076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2699; }","duration":"106.269487ms","start":"2026-07-14T22:54:36.204274Z","end":"2026-07-14T22:54:36.310544Z","steps":["trace[1403863076] 'range keys from in-memory index tree' (duration: 105.993864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:36.310672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.802211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c249e653224d81\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-14T22:54:36.31077Z","caller":"traceutil/trace.go:171","msg":"trace[1301240923] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c249e653224d81; range_end:; response_count:1; response_revision:2699; }","duration":"331.021174ms","start":"2026-07-14T22:54:35.979725Z","end":"2026-07-14T22:54:36.310746Z","steps":["trace[1301240923] 'range keys from in-memory index tree' (duration: 330.617639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:36.310823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:54:35.979707Z","time spent":"331.099275ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":803,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c249e653224d81\" "} {"level":"warn","ts":"2026-07-14T22:54:36.311086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.349909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-14T22:54:36.311272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.898643ms","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-14T22:54:36.311355Z","caller":"traceutil/trace.go:171","msg":"trace[1885278991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2699; }","duration":"229.013154ms","start":"2026-07-14T22:54:36.082329Z","end":"2026-07-14T22:54:36.311342Z","steps":["trace[1885278991] 'range keys from in-memory index tree' (duration: 228.71212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:36.311426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.780046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-14T22:54:36.311521Z","caller":"traceutil/trace.go:171","msg":"trace[1954839134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2699; }","duration":"196.893948ms","start":"2026-07-14T22:54:36.114612Z","end":"2026-07-14T22:54:36.311506Z","steps":["trace[1954839134] 'range keys from in-memory index tree' (duration: 196.664715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:36.311694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.457989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-14T22:54:36.311954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.084693ms","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-14T22:54:36.311989Z","caller":"traceutil/trace.go:171","msg":"trace[1686612035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2699; }","duration":"203.786654ms","start":"2026-07-14T22:54:36.108186Z","end":"2026-07-14T22:54:36.311973Z","steps":["trace[1686612035] 'range keys from in-memory index tree' (duration: 203.340817ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:54:36.312002Z","caller":"traceutil/trace.go:171","msg":"trace[1680512389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2699; }","duration":"200.157563ms","start":"2026-07-14T22:54:36.111831Z","end":"2026-07-14T22:54:36.311988Z","steps":["trace[1680512389] 'range keys from in-memory index tree' (duration: 199.967921ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:54:36.311152Z","caller":"traceutil/trace.go:171","msg":"trace[2968065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2699; }","duration":"234.45592ms","start":"2026-07-14T22:54:36.076684Z","end":"2026-07-14T22:54:36.31114Z","steps":["trace[2968065] 'range keys from in-memory index tree' (duration: 234.152296ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:54:36.864649Z","caller":"traceutil/trace.go:171","msg":"trace[1810943822] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"550.259522ms","start":"2026-07-14T22:54:36.314361Z","end":"2026-07-14T22:54:36.864621Z","steps":["trace[1810943822] 'process raft request' (duration: 550.036499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:36.864805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:54:36.31434Z","time spent":"550.385743ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:54:37.168008Z","caller":"traceutil/trace.go:171","msg":"trace[464193242] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"851.606827ms","start":"2026-07-14T22:54:36.316374Z","end":"2026-07-14T22:54:37.16798Z","steps":["trace[464193242] 'process raft request' (duration: 848.15824ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:54:37.168186Z","caller":"traceutil/trace.go:171","msg":"trace[710315120] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"851.316733ms","start":"2026-07-14T22:54:36.316818Z","end":"2026-07-14T22:54:37.168135Z","steps":["trace[710315120] 'process raft request' (duration: 851.158031ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:54:37.168314Z","caller":"traceutil/trace.go:171","msg":"trace[933993576] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"850.978969ms","start":"2026-07-14T22:54:36.317318Z","end":"2026-07-14T22:54:37.168297Z","steps":["trace[933993576] 'process raft request' (duration: 850.745935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:37.168326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:54:36.316808Z","time spent":"851.451165ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:54:37.168353Z","caller":"traceutil/trace.go:171","msg":"trace[556860901] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"850.482671ms","start":"2026-07-14T22:54:36.317846Z","end":"2026-07-14T22:54:37.168329Z","steps":["trace[556860901] 'process raft request' (duration: 850.37543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:37.16841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:54:36.316348Z","time spent":"851.947672ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:54:37.168506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:54:36.317834Z","time spent":"850.605783ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:54:37.168579Z","caller":"traceutil/trace.go:171","msg":"trace[2125300639] linearizableReadLoop","detail":"{readStateIndex:2812; appliedIndex:2807; }","duration":"484.550282ms","start":"2026-07-14T22:54:36.684017Z","end":"2026-07-14T22:54:37.168567Z","steps":["trace[2125300639] 'read index received' (duration: 180.681172ms)","trace[2125300639] 'applied index is now lower than readState.Index' (duration: 303.86813ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:54:37.168479Z","caller":"traceutil/trace.go:171","msg":"trace[1368480926] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"484.298429ms","start":"2026-07-14T22:54:36.684169Z","end":"2026-07-14T22:54:37.168468Z","steps":["trace[1368480926] 'process raft request' (duration: 484.125147ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:54:37.168642Z","caller":"traceutil/trace.go:171","msg":"trace[1173214071] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"370.03135ms","start":"2026-07-14T22:54:36.798598Z","end":"2026-07-14T22:54:37.16863Z","steps":["trace[1173214071] 'process raft request' (duration: 369.822967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:37.168683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:54:36.684158Z","time spent":"484.498142ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:54:37.16869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.668974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-07-14T22:54:37.168704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:54:36.798574Z","time spent":"370.097311ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:54:37.168715Z","caller":"traceutil/trace.go:171","msg":"trace[1598812153] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2707; }","duration":"484.730895ms","start":"2026-07-14T22:54:36.683978Z","end":"2026-07-14T22:54:37.168709Z","steps":["trace[1598812153] 'agreement among raft nodes before linearized reading' (duration: 484.632794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:37.168464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:54:36.317307Z","time spent":"851.06776ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:54:37.168741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:54:36.68396Z","time spent":"484.775986ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-07-14T22:54:37.168054Z","caller":"traceutil/trace.go:171","msg":"trace[1640758332] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"851.529626ms","start":"2026-07-14T22:54:36.316494Z","end":"2026-07-14T22:54:37.168023Z","steps":["trace[1640758332] 'process raft request' (duration: 851.430695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:54:37.168791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:54:36.316482Z","time spent":"852.289766ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:55:44.103979Z","caller":"traceutil/trace.go:171","msg":"trace[812006119] transaction","detail":"{read_only:false; response_revision:3061; number_of_response:1; }","duration":"176.257736ms","start":"2026-07-14T22:55:43.927693Z","end":"2026-07-14T22:55:44.103951Z","steps":["trace[812006119] 'process raft request' (duration: 176.158012ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:55:44.104353Z","caller":"traceutil/trace.go:171","msg":"trace[1550685067] transaction","detail":"{read_only:false; response_revision:3060; number_of_response:1; }","duration":"183.957462ms","start":"2026-07-14T22:55:43.920353Z","end":"2026-07-14T22:55:44.10431Z","steps":["trace[1550685067] 'process raft request' (duration: 177.976986ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:22.186055Z","caller":"traceutil/trace.go:171","msg":"trace[1467930332] transaction","detail":"{read_only:false; response_revision:4423; number_of_response:1; }","duration":"192.649215ms","start":"2026-07-14T22:58:21.993366Z","end":"2026-07-14T22:58:22.186015Z","steps":["trace[1467930332] 'process raft request' (duration: 192.466401ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:22.196746Z","caller":"traceutil/trace.go:171","msg":"trace[1621169884] linearizableReadLoop","detail":"{readStateIndex:4601; appliedIndex:4599; }","duration":"108.432578ms","start":"2026-07-14T22:58:22.088299Z","end":"2026-07-14T22:58:22.196732Z","steps":["trace[1621169884] 'read index received' (duration: 97.566946ms)","trace[1621169884] 'applied index is now lower than readState.Index' (duration: 10.863132ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:58:22.196863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.5435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:58:22.196894Z","caller":"traceutil/trace.go:171","msg":"trace[1267183538] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4425; }","duration":"108.635042ms","start":"2026-07-14T22:58:22.08825Z","end":"2026-07-14T22:58:22.196885Z","steps":["trace[1267183538] 'agreement among raft nodes before linearized reading' (duration: 108.54219ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:22.197134Z","caller":"traceutil/trace.go:171","msg":"trace[2002941053] transaction","detail":"{read_only:false; response_revision:4424; number_of_response:1; }","duration":"203.546408ms","start":"2026-07-14T22:58:21.99358Z","end":"2026-07-14T22:58:22.197126Z","steps":["trace[2002941053] 'process raft request' (duration: 203.048096ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:26.870042Z","caller":"traceutil/trace.go:171","msg":"trace[961008636] linearizableReadLoop","detail":"{readStateIndex:4759; appliedIndex:4758; }","duration":"132.767786ms","start":"2026-07-14T22:58:26.737253Z","end":"2026-07-14T22:58:26.870021Z","steps":["trace[961008636] 'read index received' (duration: 132.528581ms)","trace[961008636] 'applied index is now lower than readState.Index' (duration: 237.025µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:26.870163Z","caller":"traceutil/trace.go:171","msg":"trace[251414663] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"134.799513ms","start":"2026-07-14T22:58:26.735353Z","end":"2026-07-14T22:58:26.870153Z","steps":["trace[251414663] 'process raft request' (duration: 134.501156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:26.870579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.296018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-07-14T22:58:26.870686Z","caller":"traceutil/trace.go:171","msg":"trace[2103402861] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4579; }","duration":"133.434722ms","start":"2026-07-14T22:58:26.737225Z","end":"2026-07-14T22:58:26.870659Z","steps":["trace[2103402861] 'agreement among raft nodes before linearized reading' (duration: 133.067323ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:26.875723Z","caller":"traceutil/trace.go:171","msg":"trace[138317337] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4580; }","duration":"137.72107ms","start":"2026-07-14T22:58:26.737984Z","end":"2026-07-14T22:58:26.875705Z","steps":["trace[138317337] 'process raft request' (duration: 137.27389ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:26.875898Z","caller":"traceutil/trace.go:171","msg":"trace[2107481441] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"137.988556ms","start":"2026-07-14T22:58:26.737901Z","end":"2026-07-14T22:58:26.87589Z","steps":["trace[2107481441] 'process raft request' (duration: 137.193258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:26.877183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.951377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4737"} {"level":"info","ts":"2026-07-14T22:58:26.877854Z","caller":"traceutil/trace.go:171","msg":"trace[935337665] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4582; }","duration":"137.220079ms","start":"2026-07-14T22:58:26.740615Z","end":"2026-07-14T22:58:26.877835Z","steps":["trace[935337665] 'agreement among raft nodes before linearized reading' (duration: 134.799863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:28.108473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.736726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-07-14T22:58:28.108571Z","caller":"traceutil/trace.go:171","msg":"trace[1897606668] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4606; }","duration":"200.882599ms","start":"2026-07-14T22:58:27.907668Z","end":"2026-07-14T22:58:28.10855Z","steps":["trace[1897606668] 'range keys from in-memory index tree' (duration: 200.536371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:28.109112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.325625ms","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":"info","ts":"2026-07-14T22:58:28.10915Z","caller":"traceutil/trace.go:171","msg":"trace[1730043364] 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:4606; }","duration":"304.392537ms","start":"2026-07-14T22:58:27.804746Z","end":"2026-07-14T22:58:28.109138Z","steps":["trace[1730043364] 'range keys from in-memory index tree' (duration: 303.438595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:28.109184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:27.80473Z","time spent":"304.435727ms","remote":"127.0.0.1:35458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-07-14T22:58:34.205211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.724221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-14T22:58:34.205362Z","caller":"traceutil/trace.go:171","msg":"trace[1831537554] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4713; }","duration":"674.933925ms","start":"2026-07-14T22:58:33.530408Z","end":"2026-07-14T22:58:34.205342Z","steps":["trace[1831537554] 'range keys from in-memory index tree' (duration: 674.409834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:34.205405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:33.530359Z","time spent":"675.035687ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-14T22:58:34.205948Z","caller":"traceutil/trace.go:171","msg":"trace[936086005] linearizableReadLoop","detail":"{readStateIndex:4900; appliedIndex:4899; }","duration":"449.747499ms","start":"2026-07-14T22:58:33.756176Z","end":"2026-07-14T22:58:34.205923Z","steps":["trace[936086005] 'read index received' (duration: 446.68961ms)","trace[936086005] 'applied index is now lower than readState.Index' (duration: 3.056819ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:34.206081Z","caller":"traceutil/trace.go:171","msg":"trace[463708131] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"592.23496ms","start":"2026-07-14T22:58:33.613835Z","end":"2026-07-14T22:58:34.20607Z","steps":["trace[463708131] 'process raft request' (duration: 589.015747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:34.206115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.935013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f4541610-9932-4210-9ec1-b228422ba59a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-14T22:58:34.206141Z","caller":"traceutil/trace.go:171","msg":"trace[134275991] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f4541610-9932-4210-9ec1-b228422ba59a; range_end:; response_count:1; response_revision:4714; }","duration":"450.015775ms","start":"2026-07-14T22:58:33.756117Z","end":"2026-07-14T22:58:34.206133Z","steps":["trace[134275991] 'agreement among raft nodes before linearized reading' (duration: 449.890612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:34.206155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:33.613793Z","time spent":"592.314301ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:58:34.206163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:33.756093Z","time spent":"450.064387ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f4541610-9932-4210-9ec1-b228422ba59a\" "} {"level":"info","ts":"2026-07-14T22:58:34.42665Z","caller":"traceutil/trace.go:171","msg":"trace[1266131704] linearizableReadLoop","detail":"{readStateIndex:4901; appliedIndex:4900; }","duration":"217.501682ms","start":"2026-07-14T22:58:34.209117Z","end":"2026-07-14T22:58:34.426619Z","steps":["trace[1266131704] 'read index received' (duration: 162.59624ms)","trace[1266131704] 'applied index is now lower than readState.Index' (duration: 54.904002ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:34.426738Z","caller":"traceutil/trace.go:171","msg":"trace[353525474] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"261.644981ms","start":"2026-07-14T22:58:34.16505Z","end":"2026-07-14T22:58:34.426695Z","steps":["trace[353525474] 'process raft request' (duration: 206.431512ms)","trace[353525474] 'compare' (duration: 54.992414ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:58:34.426858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.713657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:58:34.426932Z","caller":"traceutil/trace.go:171","msg":"trace[434892987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4715; }","duration":"217.821229ms","start":"2026-07-14T22:58:34.209099Z","end":"2026-07-14T22:58:34.42692Z","steps":["trace[434892987] 'agreement among raft nodes before linearized reading' (duration: 217.653516ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:34.436568Z","caller":"traceutil/trace.go:171","msg":"trace[591178002] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"227.383176ms","start":"2026-07-14T22:58:34.209114Z","end":"2026-07-14T22:58:34.436497Z","steps":["trace[591178002] 'process raft request' (duration: 227.216602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:34.436749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.272007ms","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":"info","ts":"2026-07-14T22:58:34.436795Z","caller":"traceutil/trace.go:171","msg":"trace[2119529066] 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:4718; }","duration":"206.3613ms","start":"2026-07-14T22:58:34.230419Z","end":"2026-07-14T22:58:34.43678Z","steps":["trace[2119529066] 'agreement among raft nodes before linearized reading' (duration: 206.186516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:34.436841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.546606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-07-14T22:58:34.43693Z","caller":"traceutil/trace.go:171","msg":"trace[1589093527] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4718; }","duration":"222.641088ms","start":"2026-07-14T22:58:34.214264Z","end":"2026-07-14T22:58:34.436905Z","steps":["trace[1589093527] 'agreement among raft nodes before linearized reading' (duration: 222.460734ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:34.436984Z","caller":"traceutil/trace.go:171","msg":"trace[360248241] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"152.817108ms","start":"2026-07-14T22:58:34.284157Z","end":"2026-07-14T22:58:34.436974Z","steps":["trace[360248241] 'process raft request' (duration: 152.281686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:34.437074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.838831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-14T22:58:34.437097Z","caller":"traceutil/trace.go:171","msg":"trace[1714280297] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"151.935919ms","start":"2026-07-14T22:58:34.285154Z","end":"2026-07-14T22:58:34.437089Z","steps":["trace[1714280297] 'process raft request' (duration: 151.330525ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:34.437119Z","caller":"traceutil/trace.go:171","msg":"trace[1316406247] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4718; }","duration":"225.913113ms","start":"2026-07-14T22:58:34.211193Z","end":"2026-07-14T22:58:34.437107Z","steps":["trace[1316406247] 'agreement among raft nodes before linearized reading' (duration: 225.76833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:35.159144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.098186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T22:58:35.159229Z","caller":"traceutil/trace.go:171","msg":"trace[795143210] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4736; }","duration":"282.266611ms","start":"2026-07-14T22:58:34.87694Z","end":"2026-07-14T22:58:35.159206Z","steps":["trace[795143210] 'count revisions from in-memory index tree' (duration: 281.975114ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:35.159261Z","caller":"traceutil/trace.go:171","msg":"trace[102179233] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"254.639185ms","start":"2026-07-14T22:58:34.904598Z","end":"2026-07-14T22:58:35.159237Z","steps":["trace[102179233] 'process raft request' (duration: 172.539211ms)","trace[102179233] 'compare' (duration: 81.91315ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:35.163639Z","caller":"traceutil/trace.go:171","msg":"trace[779214999] linearizableReadLoop","detail":"{readStateIndex:4924; appliedIndex:4922; }","duration":"178.386209ms","start":"2026-07-14T22:58:34.985228Z","end":"2026-07-14T22:58:35.163614Z","steps":["trace[779214999] 'read index received' (duration: 92.112611ms)","trace[779214999] 'applied index is now lower than readState.Index' (duration: 86.272588ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:58:35.163799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.576334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T22:58:35.163833Z","caller":"traceutil/trace.go:171","msg":"trace[766342060] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4738; }","duration":"178.669326ms","start":"2026-07-14T22:58:34.985155Z","end":"2026-07-14T22:58:35.163824Z","steps":["trace[766342060] 'agreement among raft nodes before linearized reading' (duration: 178.562254ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:35.163796Z","caller":"traceutil/trace.go:171","msg":"trace[601668827] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"256.944357ms","start":"2026-07-14T22:58:34.906838Z","end":"2026-07-14T22:58:35.163782Z","steps":["trace[601668827] 'process raft request' (duration: 256.660461ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:35.424894Z","caller":"traceutil/trace.go:171","msg":"trace[691887407] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"190.782899ms","start":"2026-07-14T22:58:35.234065Z","end":"2026-07-14T22:58:35.424848Z","steps":["trace[691887407] 'process raft request' (duration: 190.506593ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:38.611645Z","caller":"traceutil/trace.go:171","msg":"trace[73763417] linearizableReadLoop","detail":"{readStateIndex:4964; appliedIndex:4961; }","duration":"127.166564ms","start":"2026-07-14T22:58:38.484458Z","end":"2026-07-14T22:58:38.611625Z","steps":["trace[73763417] 'read index received' (duration: 116.766151ms)","trace[73763417] 'applied index is now lower than readState.Index' (duration: 10.399323ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:38.611858Z","caller":"traceutil/trace.go:171","msg":"trace[1241789020] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"160.466111ms","start":"2026-07-14T22:58:38.451338Z","end":"2026-07-14T22:58:38.611804Z","steps":["trace[1241789020] 'process raft request' (duration: 160.251476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:38.612021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.537172ms","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-14T22:58:38.612061Z","caller":"traceutil/trace.go:171","msg":"trace[577920278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4777; }","duration":"127.631765ms","start":"2026-07-14T22:58:38.484419Z","end":"2026-07-14T22:58:38.612051Z","steps":["trace[577920278] 'agreement among raft nodes before linearized reading' (duration: 127.462611ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:38.612193Z","caller":"traceutil/trace.go:171","msg":"trace[1795200286] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"309.118617ms","start":"2026-07-14T22:58:38.303051Z","end":"2026-07-14T22:58:38.61217Z","steps":["trace[1795200286] 'process raft request' (duration: 298.142021ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:38.612187Z","caller":"traceutil/trace.go:171","msg":"trace[565832772] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"161.428393ms","start":"2026-07-14T22:58:38.450721Z","end":"2026-07-14T22:58:38.612149Z","steps":["trace[565832772] 'process raft request' (duration: 160.813939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:38.612284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.066959ms","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-14T22:58:38.612326Z","caller":"traceutil/trace.go:171","msg":"trace[1190164820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4777; }","duration":"122.145302ms","start":"2026-07-14T22:58:38.490169Z","end":"2026-07-14T22:58:38.612315Z","steps":["trace[1190164820] 'agreement among raft nodes before linearized reading' (duration: 121.955757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:38.612339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:38.30303Z","time spent":"309.22258ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9564,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:58:43.483291Z","caller":"traceutil/trace.go:171","msg":"trace[107882776] linearizableReadLoop","detail":"{readStateIndex:5056; appliedIndex:5055; }","duration":"130.787466ms","start":"2026-07-14T22:58:43.352472Z","end":"2026-07-14T22:58:43.483259Z","steps":["trace[107882776] 'read index received' (duration: 130.485149ms)","trace[107882776] 'applied index is now lower than readState.Index' (duration: 298.617µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:58:43.483645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.103133ms","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":"info","ts":"2026-07-14T22:58:43.483714Z","caller":"traceutil/trace.go:171","msg":"trace[190654187] 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:4864; }","duration":"131.240066ms","start":"2026-07-14T22:58:43.352457Z","end":"2026-07-14T22:58:43.483697Z","steps":["trace[190654187] 'agreement among raft nodes before linearized reading' (duration: 130.924319ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:43.483891Z","caller":"traceutil/trace.go:171","msg":"trace[735580206] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"142.284921ms","start":"2026-07-14T22:58:43.341404Z","end":"2026-07-14T22:58:43.483689Z","steps":["trace[735580206] 'process raft request' (duration: 141.670158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:48.707233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844754581771801,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T22:58:49.342532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.328414ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-14T22:58:49.342964Z","caller":"traceutil/trace.go:171","msg":"trace[1906602751] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"695.802025ms","start":"2026-07-14T22:58:48.647136Z","end":"2026-07-14T22:58:49.342939Z","steps":["trace[1906602751] 'process raft request' (duration: 695.520018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:49.343094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:48.647107Z","time spent":"695.920737ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:58:49.406626Z","caller":"traceutil/trace.go:171","msg":"trace[1483248494] linearizableReadLoop","detail":"{readStateIndex:5106; appliedIndex:5103; }","duration":"1.199934113s","start":"2026-07-14T22:58:48.206657Z","end":"2026-07-14T22:58:49.406592Z","steps":["trace[1483248494] 'read index received' (duration: 560.622205ms)","trace[1483248494] 'applied index is now lower than readState.Index' (duration: 639.310468ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:49.406758Z","caller":"traceutil/trace.go:171","msg":"trace[561837207] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"755.673948ms","start":"2026-07-14T22:58:48.651071Z","end":"2026-07-14T22:58:49.406745Z","steps":["trace[561837207] 'process raft request' (duration: 691.849517ms)","trace[561837207] 'compare' (duration: 63.435703ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:58:49.406815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200142437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T22:58:49.406867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:48.651043Z","time spent":"755.74051ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:58:49.406921Z","caller":"traceutil/trace.go:171","msg":"trace[871825175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4913; }","duration":"1.200302531s","start":"2026-07-14T22:58:48.206595Z","end":"2026-07-14T22:58:49.406897Z","steps":["trace[871825175] 'agreement among raft nodes before linearized reading' (duration: 1.200146987s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:49.406975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:48.206573Z","time spent":"1.200390183s","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T22:58:49.430138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.012091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T22:58:49.430172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.396524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-14T22:58:49.430188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.355506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T22:58:49.430241Z","caller":"traceutil/trace.go:171","msg":"trace[170127566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4915; }","duration":"756.490165ms","start":"2026-07-14T22:58:48.673732Z","end":"2026-07-14T22:58:49.430223Z","steps":["trace[170127566] 'agreement among raft nodes before linearized reading' (duration: 756.279911ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:49.430244Z","caller":"traceutil/trace.go:171","msg":"trace[1168234011] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:4915; }","duration":"261.159295ms","start":"2026-07-14T22:58:49.169056Z","end":"2026-07-14T22:58:49.430216Z","steps":["trace[1168234011] 'agreement among raft nodes before linearized reading' (duration: 260.980071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:49.430227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.776084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-14T22:58:49.430279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:48.67372Z","time spent":"756.550927ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T22:58:49.430317Z","caller":"traceutil/trace.go:171","msg":"trace[622938533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4915; }","duration":"757.902577ms","start":"2026-07-14T22:58:48.672392Z","end":"2026-07-14T22:58:49.430295Z","steps":["trace[622938533] 'agreement among raft nodes before linearized reading' (duration: 757.647241ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:49.430323Z","caller":"traceutil/trace.go:171","msg":"trace[1017950952] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"221.013953ms","start":"2026-07-14T22:58:49.209291Z","end":"2026-07-14T22:58:49.430305Z","steps":["trace[1017950952] 'process raft request' (duration: 220.524262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:49.430361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:48.67238Z","time spent":"757.972458ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T22:58:49.430462Z","caller":"traceutil/trace.go:171","msg":"trace[1106253212] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"217.413994ms","start":"2026-07-14T22:58:49.213035Z","end":"2026-07-14T22:58:49.430449Z","steps":["trace[1106253212] 'process raft request' (duration: 216.914283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:49.430675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.980755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-14T22:58:49.430707Z","caller":"traceutil/trace.go:171","msg":"trace[1036413969] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4915; }","duration":"966.055877ms","start":"2026-07-14T22:58:48.464639Z","end":"2026-07-14T22:58:49.430695Z","steps":["trace[1036413969] 'agreement among raft nodes before linearized reading' (duration: 965.966105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:49.430736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:48.464608Z","time spent":"966.120798ms","remote":"127.0.0.1:35458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-14T22:58:49.430808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054144905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16552"} {"level":"info","ts":"2026-07-14T22:58:49.430249Z","caller":"traceutil/trace.go:171","msg":"trace[1000297410] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:4915; }","duration":"468.460238ms","start":"2026-07-14T22:58:48.961774Z","end":"2026-07-14T22:58:49.430234Z","steps":["trace[1000297410] 'agreement among raft nodes before linearized reading' (duration: 468.337836ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:49.430864Z","caller":"traceutil/trace.go:171","msg":"trace[1158954080] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4915; }","duration":"1.054259867s","start":"2026-07-14T22:58:48.376586Z","end":"2026-07-14T22:58:49.430846Z","steps":["trace[1158954080] 'agreement among raft nodes before linearized reading' (duration: 1.054078693s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:49.430871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:48.961754Z","time spent":"469.101313ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-14T22:58:49.43091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:48.376568Z","time spent":"1.054329009s","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16574,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-07-14T22:58:49.430944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.071819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-14T22:58:49.430977Z","caller":"traceutil/trace.go:171","msg":"trace[37634142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4915; }","duration":"762.145681ms","start":"2026-07-14T22:58:48.668822Z","end":"2026-07-14T22:58:49.430968Z","steps":["trace[37634142] 'agreement among raft nodes before linearized reading' (duration: 762.052869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:49.431004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:48.6688Z","time spent":"762.198351ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T22:58:49.431045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.503138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-14T22:58:49.431056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.888152ms","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-14T22:58:49.431108Z","caller":"traceutil/trace.go:171","msg":"trace[898146397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4915; }","duration":"762.60229ms","start":"2026-07-14T22:58:48.668489Z","end":"2026-07-14T22:58:49.431092Z","steps":["trace[898146397] 'agreement among raft nodes before linearized reading' (duration: 762.459657ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:49.431116Z","caller":"traceutil/trace.go:171","msg":"trace[494294761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4915; }","duration":"765.998566ms","start":"2026-07-14T22:58:48.665101Z","end":"2026-07-14T22:58:49.4311Z","steps":["trace[494294761] 'agreement among raft nodes before linearized reading' (duration: 765.836432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:49.431148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:48.665079Z","time spent":"766.061816ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T22:58:49.431151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:48.668466Z","time spent":"762.672861ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T22:58:49.431288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.475287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:58:49.431362Z","caller":"traceutil/trace.go:171","msg":"trace[300483784] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4915; }","duration":"447.568829ms","start":"2026-07-14T22:58:48.983771Z","end":"2026-07-14T22:58:49.431339Z","steps":["trace[300483784] 'agreement among raft nodes before linearized reading' (duration: 447.428436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:49.431409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:48.983754Z","time spent":"447.640781ms","remote":"127.0.0.1:53038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-14T22:58:49.582069Z","caller":"traceutil/trace.go:171","msg":"trace[1479770778] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"146.137559ms","start":"2026-07-14T22:58:49.435906Z","end":"2026-07-14T22:58:49.582044Z","steps":["trace[1479770778] 'process raft request' (duration: 145.993676ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:49.58244Z","caller":"traceutil/trace.go:171","msg":"trace[1600146261] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"127.772766ms","start":"2026-07-14T22:58:49.454649Z","end":"2026-07-14T22:58:49.582422Z","steps":["trace[1600146261] 'process raft request' (duration: 127.582231ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:49.582515Z","caller":"traceutil/trace.go:171","msg":"trace[2293171] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5108; }","duration":"127.680654ms","start":"2026-07-14T22:58:49.454818Z","end":"2026-07-14T22:58:49.582498Z","steps":["trace[2293171] 'read index received' (duration: 54.907236ms)","trace[2293171] 'applied index is now lower than readState.Index' (duration: 72.772578ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:49.582624Z","caller":"traceutil/trace.go:171","msg":"trace[1822754401] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"122.221764ms","start":"2026-07-14T22:58:49.460392Z","end":"2026-07-14T22:58:49.582614Z","steps":["trace[1822754401] 'process raft request' (duration: 121.982368ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:49.582747Z","caller":"traceutil/trace.go:171","msg":"trace[245770824] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"127.93711ms","start":"2026-07-14T22:58:49.454773Z","end":"2026-07-14T22:58:49.58271Z","steps":["trace[245770824] 'process raft request' (duration: 127.556121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:49.582899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.891408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-14T22:58:49.582974Z","caller":"traceutil/trace.go:171","msg":"trace[1275677491] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4921; }","duration":"127.990851ms","start":"2026-07-14T22:58:49.45496Z","end":"2026-07-14T22:58:49.582951Z","steps":["trace[1275677491] 'agreement among raft nodes before linearized reading' (duration: 127.787817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:49.582992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.162515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:58:49.583053Z","caller":"traceutil/trace.go:171","msg":"trace[2038520878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4921; }","duration":"128.232696ms","start":"2026-07-14T22:58:49.454812Z","end":"2026-07-14T22:58:49.583045Z","steps":["trace[2038520878] 'agreement among raft nodes before linearized reading' (duration: 128.120504ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:49.582369Z","caller":"traceutil/trace.go:171","msg":"trace[750696636] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"127.912969ms","start":"2026-07-14T22:58:49.454395Z","end":"2026-07-14T22:58:49.582308Z","steps":["trace[750696636] 'process raft request' (duration: 127.600762ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:52.557219Z","caller":"traceutil/trace.go:171","msg":"trace[1840968957] linearizableReadLoop","detail":"{readStateIndex:5129; appliedIndex:5128; }","duration":"114.355347ms","start":"2026-07-14T22:58:52.442845Z","end":"2026-07-14T22:58:52.5572Z","steps":["trace[1840968957] 'read index received' (duration: 114.181423ms)","trace[1840968957] 'applied index is now lower than readState.Index' (duration: 173.274µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:52.557325Z","caller":"traceutil/trace.go:171","msg":"trace[1043287387] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"142.42813ms","start":"2026-07-14T22:58:52.414871Z","end":"2026-07-14T22:58:52.5573Z","steps":["trace[1043287387] 'process raft request' (duration: 142.219816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:52.557665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.548961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-14T22:58:52.557699Z","caller":"traceutil/trace.go:171","msg":"trace[41747321] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4936; }","duration":"114.871799ms","start":"2026-07-14T22:58:52.442819Z","end":"2026-07-14T22:58:52.557691Z","steps":["trace[41747321] 'agreement among raft nodes before linearized reading' (duration: 114.451759ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:17.571866Z","caller":"traceutil/trace.go:171","msg":"trace[1007947908] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"125.890641ms","start":"2026-07-14T22:59:17.445948Z","end":"2026-07-14T22:59:17.571839Z","steps":["trace[1007947908] 'process raft request' (duration: 125.734908ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:17.573706Z","caller":"traceutil/trace.go:171","msg":"trace[1328647002] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"117.932005ms","start":"2026-07-14T22:59:17.455749Z","end":"2026-07-14T22:59:17.573681Z","steps":["trace[1328647002] 'process raft request' (duration: 117.811272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:18.230087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.436827ms","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-14T22:59:18.230176Z","caller":"traceutil/trace.go:171","msg":"trace[1825858313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5094; }","duration":"439.57637ms","start":"2026-07-14T22:59:17.790579Z","end":"2026-07-14T22:59:18.230156Z","steps":["trace[1825858313] 'range keys from in-memory index tree' (duration: 439.272734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:18.230226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:59:17.790552Z","time spent":"439.661612ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T22:59:18.230534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.739641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3315"} {"level":"info","ts":"2026-07-14T22:59:18.230591Z","caller":"traceutil/trace.go:171","msg":"trace[1071309592] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5094; }","duration":"425.841853ms","start":"2026-07-14T22:59:17.804733Z","end":"2026-07-14T22:59:18.230575Z","steps":["trace[1071309592] 'range keys from in-memory index tree' (duration: 425.564647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:18.230634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:59:17.804707Z","time spent":"425.916505ms","remote":"127.0.0.1:55648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3337,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-07-14T22:59:18.231051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.523398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T22:59:18.23156Z","caller":"traceutil/trace.go:171","msg":"trace[13228267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5094; }","duration":"406.045369ms","start":"2026-07-14T22:59:17.825487Z","end":"2026-07-14T22:59:18.231533Z","steps":["trace[13228267] 'range keys from in-memory index tree' (duration: 405.377546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:18.232395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:59:17.82548Z","time spent":"406.896047ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T22:59:18.23163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.102017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-14T22:59:18.232614Z","caller":"traceutil/trace.go:171","msg":"trace[537801434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5094; }","duration":"403.114688ms","start":"2026-07-14T22:59:17.829481Z","end":"2026-07-14T22:59:18.232596Z","steps":["trace[537801434] 'range keys from in-memory index tree' (duration: 401.986175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:18.232661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:59:17.82946Z","time spent":"403.187789ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T22:59:18.231764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.567117ms","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-14T22:59:18.232812Z","caller":"traceutil/trace.go:171","msg":"trace[944243390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5094; }","duration":"360.692721ms","start":"2026-07-14T22:59:17.872107Z","end":"2026-07-14T22:59:18.2328Z","steps":["trace[944243390] 'range keys from in-memory index tree' (duration: 359.341623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:18.232844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:59:17.87206Z","time spent":"360.775183ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T22:59:18.249735Z","caller":"traceutil/trace.go:171","msg":"trace[27448237] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"350.483128ms","start":"2026-07-14T22:59:17.899213Z","end":"2026-07-14T22:59:18.249696Z","steps":["trace[27448237] 'process raft request' (duration: 350.276793ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:18.249828Z","caller":"traceutil/trace.go:171","msg":"trace[1418638535] linearizableReadLoop","detail":"{readStateIndex:5295; appliedIndex:5292; }","duration":"183.969969ms","start":"2026-07-14T22:59:18.065827Z","end":"2026-07-14T22:59:18.249797Z","steps":["trace[1418638535] 'read index received' (duration: 158.662781ms)","trace[1418638535] 'applied index is now lower than readState.Index' (duration: 25.305678ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:59:18.249845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:59:17.899188Z","time spent":"350.590529ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:59:18.249905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.081531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:59:18.24993Z","caller":"traceutil/trace.go:171","msg":"trace[651200772] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5097; }","duration":"184.153132ms","start":"2026-07-14T22:59:18.06577Z","end":"2026-07-14T22:59:18.249923Z","steps":["trace[651200772] 'agreement among raft nodes before linearized reading' (duration: 184.100711ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:18.249815Z","caller":"traceutil/trace.go:171","msg":"trace[1317495346] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"232.016241ms","start":"2026-07-14T22:59:18.017748Z","end":"2026-07-14T22:59:18.249764Z","steps":["trace[1317495346] 'process raft request' (duration: 231.881058ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:29.950789Z","caller":"traceutil/trace.go:171","msg":"trace[216648516] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"184.319291ms","start":"2026-07-14T22:59:29.766445Z","end":"2026-07-14T22:59:29.950765Z","steps":["trace[216648516] 'process raft request' (duration: 183.75382ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:29.958299Z","caller":"traceutil/trace.go:171","msg":"trace[1012276846] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"169.365304ms","start":"2026-07-14T22:59:29.78891Z","end":"2026-07-14T22:59:29.958275Z","steps":["trace[1012276846] 'process raft request' (duration: 169.089129ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:29.958473Z","caller":"traceutil/trace.go:171","msg":"trace[706446760] linearizableReadLoop","detail":"{readStateIndex:5453; appliedIndex:5450; }","duration":"164.708259ms","start":"2026-07-14T22:59:29.793534Z","end":"2026-07-14T22:59:29.958242Z","steps":["trace[706446760] 'read index received' (duration: 156.678864ms)","trace[706446760] 'applied index is now lower than readState.Index' (duration: 8.028475ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:59:29.958481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.934534ms","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-14T22:59:29.958528Z","caller":"traceutil/trace.go:171","msg":"trace[375579039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5251; }","duration":"165.036705ms","start":"2026-07-14T22:59:29.793483Z","end":"2026-07-14T22:59:29.95852Z","steps":["trace[375579039] 'agreement among raft nodes before linearized reading' (duration: 164.861662ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:29.958708Z","caller":"traceutil/trace.go:171","msg":"trace[1575066851] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"175.558371ms","start":"2026-07-14T22:59:29.783112Z","end":"2026-07-14T22:59:29.95867Z","steps":["trace[1575066851] 'process raft request' (duration: 174.755635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:35.924659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.156371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-07-14T22:59:35.924832Z","caller":"traceutil/trace.go:171","msg":"trace[1412962305] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5282; }","duration":"143.376686ms","start":"2026-07-14T22:59:35.781436Z","end":"2026-07-14T22:59:35.924813Z","steps":["trace[1412962305] 'range keys from in-memory index tree' (duration: 142.976927ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:35.9441Z","caller":"traceutil/trace.go:171","msg":"trace[1125013935] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"151.381759ms","start":"2026-07-14T22:59:35.792696Z","end":"2026-07-14T22:59:35.944078Z","steps":["trace[1125013935] 'process raft request' (duration: 151.240086ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:36.125427Z","caller":"traceutil/trace.go:171","msg":"trace[550573748] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"157.251394ms","start":"2026-07-14T22:59:35.968145Z","end":"2026-07-14T22:59:36.125397Z","steps":["trace[550573748] 'process raft request' (duration: 59.399017ms)","trace[550573748] 'compare' (duration: 97.629792ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:59:36.12564Z","caller":"traceutil/trace.go:171","msg":"trace[1988723527] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"152.584529ms","start":"2026-07-14T22:59:35.973038Z","end":"2026-07-14T22:59:36.125622Z","steps":["trace[1988723527] 'process raft request' (duration: 152.305984ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:36.125905Z","caller":"traceutil/trace.go:171","msg":"trace[88579681] linearizableReadLoop","detail":"{readStateIndex:5489; appliedIndex:5487; }","duration":"150.828353ms","start":"2026-07-14T22:59:35.975056Z","end":"2026-07-14T22:59:36.125884Z","steps":["trace[88579681] 'read index received' (duration: 52.513427ms)","trace[88579681] 'applied index is now lower than readState.Index' (duration: 98.313916ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:59:36.126137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.054138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T22:59:36.126179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.347474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:59:36.126195Z","caller":"traceutil/trace.go:171","msg":"trace[1377546603] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5287; }","duration":"151.17611ms","start":"2026-07-14T22:59:35.975002Z","end":"2026-07-14T22:59:36.126178Z","steps":["trace[1377546603] 'agreement among raft nodes before linearized reading' (duration: 151.074248ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:36.126219Z","caller":"traceutil/trace.go:171","msg":"trace[1161522172] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5287; }","duration":"122.446046ms","start":"2026-07-14T22:59:36.003762Z","end":"2026-07-14T22:59:36.126208Z","steps":["trace[1161522172] 'agreement among raft nodes before linearized reading' (duration: 122.369074ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:36.126431Z","caller":"traceutil/trace.go:171","msg":"trace[1819200608] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"150.027097ms","start":"2026-07-14T22:59:35.976389Z","end":"2026-07-14T22:59:36.126416Z","steps":["trace[1819200608] 'process raft request' (duration: 149.16403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:36.126615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.435904ms","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-14T22:59:36.126659Z","caller":"traceutil/trace.go:171","msg":"trace[1155887423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5287; }","duration":"117.515676ms","start":"2026-07-14T22:59:36.00913Z","end":"2026-07-14T22:59:36.126646Z","steps":["trace[1155887423] 'agreement among raft nodes before linearized reading' (duration: 117.318502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:36.126795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.773621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"info","ts":"2026-07-14T22:59:36.126818Z","caller":"traceutil/trace.go:171","msg":"trace[1734129735] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5287; }","duration":"117.813192ms","start":"2026-07-14T22:59:36.008998Z","end":"2026-07-14T22:59:36.126811Z","steps":["trace[1734129735] 'agreement among raft nodes before linearized reading' (duration: 117.732701ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:42.607728Z","caller":"traceutil/trace.go:171","msg":"trace[1702846667] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"212.779174ms","start":"2026-07-14T22:59:42.394927Z","end":"2026-07-14T22:59:42.607706Z","steps":["trace[1702846667] 'process raft request' (duration: 209.891326ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:42.60773Z","caller":"traceutil/trace.go:171","msg":"trace[1992406142] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"211.721202ms","start":"2026-07-14T22:59:42.39599Z","end":"2026-07-14T22:59:42.607711Z","steps":["trace[1992406142] 'process raft request' (duration: 211.660661ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:44.831094Z","caller":"traceutil/trace.go:171","msg":"trace[2113389297] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"215.018317ms","start":"2026-07-14T22:59:44.616053Z","end":"2026-07-14T22:59:44.831071Z","steps":["trace[2113389297] 'process raft request' (duration: 214.857364ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:44.831653Z","caller":"traceutil/trace.go:171","msg":"trace[1438188699] transaction","detail":"{read_only:false; response_revision:5359; number_of_response:1; }","duration":"214.724702ms","start":"2026-07-14T22:59:44.616908Z","end":"2026-07-14T22:59:44.831633Z","steps":["trace[1438188699] 'process raft request' (duration: 214.63997ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:47.1398Z","caller":"traceutil/trace.go:171","msg":"trace[1165148170] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"297.889021ms","start":"2026-07-14T22:59:46.841893Z","end":"2026-07-14T22:59:47.139782Z","steps":["trace[1165148170] 'process raft request' (duration: 297.776149ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:48.032631Z","caller":"traceutil/trace.go:171","msg":"trace[836488402] transaction","detail":"{read_only:false; response_revision:5370; number_of_response:1; }","duration":"196.514899ms","start":"2026-07-14T22:59:47.83609Z","end":"2026-07-14T22:59:48.032605Z","steps":["trace[836488402] 'process raft request' (duration: 196.350046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:48.4493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.564247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:59:48.449556Z","caller":"traceutil/trace.go:171","msg":"trace[779081442] linearizableReadLoop","detail":"{readStateIndex:5581; appliedIndex:5578; }","duration":"152.314932ms","start":"2026-07-14T22:59:48.29722Z","end":"2026-07-14T22:59:48.449535Z","steps":["trace[779081442] 'read index received' (duration: 30.446589ms)","trace[779081442] 'applied index is now lower than readState.Index' (duration: 121.867213ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:59:48.449688Z","caller":"traceutil/trace.go:171","msg":"trace[1238834869] transaction","detail":"{read_only:false; response_revision:5374; number_of_response:1; }","duration":"155.72669ms","start":"2026-07-14T22:59:48.293935Z","end":"2026-07-14T22:59:48.449662Z","steps":["trace[1238834869] 'process raft request' (duration: 155.552336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:48.449808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.578667ms","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-14T22:59:48.449868Z","caller":"traceutil/trace.go:171","msg":"trace[1136101917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5374; }","duration":"152.670189ms","start":"2026-07-14T22:59:48.297182Z","end":"2026-07-14T22:59:48.449852Z","steps":["trace[1136101917] 'agreement among raft nodes before linearized reading' (duration: 152.479505ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:48.450253Z","caller":"traceutil/trace.go:171","msg":"trace[1769240795] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"156.220649ms","start":"2026-07-14T22:59:48.293673Z","end":"2026-07-14T22:59:48.449893Z","steps":["trace[1769240795] 'process raft request' (duration: 155.73906ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:48.45048Z","caller":"traceutil/trace.go:171","msg":"trace[177756888] transaction","detail":"{read_only:false; response_revision:5372; number_of_response:1; }","duration":"165.671138ms","start":"2026-07-14T22:59:48.283855Z","end":"2026-07-14T22:59:48.449526Z","steps":["trace[177756888] 'process raft request' (duration: 43.780704ms)","trace[177756888] 'compare' (duration: 121.407294ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:59:49.087226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.77973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:59:49.087382Z","caller":"traceutil/trace.go:171","msg":"trace[670986458] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"156.852541ms","start":"2026-07-14T22:59:48.93051Z","end":"2026-07-14T22:59:49.087363Z","steps":["trace[670986458] 'process raft request' (duration: 52.852226ms)","trace[670986458] 'compare' (duration: 103.456674ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:59:49.140514Z","caller":"traceutil/trace.go:171","msg":"trace[1490333268] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"204.346708ms","start":"2026-07-14T22:59:48.936151Z","end":"2026-07-14T22:59:49.140498Z","steps":["trace[1490333268] 'process raft request' (duration: 204.137473ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:49.180847Z","caller":"traceutil/trace.go:171","msg":"trace[1343622605] linearizableReadLoop","detail":"{readStateIndex:5590; appliedIndex:5589; }","duration":"175.363088ms","start":"2026-07-14T22:59:49.005462Z","end":"2026-07-14T22:59:49.180825Z","steps":["trace[1343622605] 'read index received' (duration: 135.86523ms)","trace[1343622605] 'applied index is now lower than readState.Index' (duration: 39.496998ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:59:49.181106Z","caller":"traceutil/trace.go:171","msg":"trace[704782821] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"193.239934ms","start":"2026-07-14T22:59:48.987855Z","end":"2026-07-14T22:59:49.181094Z","steps":["trace[704782821] 'process raft request' (duration: 192.847766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:49.181295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.823167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:59:49.181326Z","caller":"traceutil/trace.go:171","msg":"trace[1936529068] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5383; }","duration":"175.901189ms","start":"2026-07-14T22:59:49.005417Z","end":"2026-07-14T22:59:49.181318Z","steps":["trace[1936529068] 'agreement among raft nodes before linearized reading' (duration: 175.836057ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:49.353982Z","caller":"traceutil/trace.go:171","msg":"trace[1806727641] linearizableReadLoop","detail":"{readStateIndex:5591; appliedIndex:5590; }","duration":"146.94529ms","start":"2026-07-14T22:59:49.206992Z","end":"2026-07-14T22:59:49.353938Z","steps":["trace[1806727641] 'read index received' (duration: 140.636434ms)","trace[1806727641] 'applied index is now lower than readState.Index' (duration: 6.307736ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:59:49.354006Z","caller":"traceutil/trace.go:171","msg":"trace[942914463] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"167.051292ms","start":"2026-07-14T22:59:49.186922Z","end":"2026-07-14T22:59:49.353974Z","steps":["trace[942914463] 'process raft request' (duration: 160.781467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:59:49.355385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.095844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:59:49.355422Z","caller":"traceutil/trace.go:171","msg":"trace[1943843176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5384; }","duration":"148.470391ms","start":"2026-07-14T22:59:49.206942Z","end":"2026-07-14T22:59:49.355413Z","steps":["trace[1943843176] 'agreement among raft nodes before linearized reading' (duration: 147.110694ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:59.382748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2826} {"level":"warn","ts":"2026-07-14T22:59:59.540931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.373361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:59:59.541148Z","caller":"traceutil/trace.go:171","msg":"trace[1146395418] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"125.818457ms","start":"2026-07-14T22:59:59.4153Z","end":"2026-07-14T22:59:59.541119Z","steps":["trace[1146395418] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:416; } (duration: 118.182837ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:59:59.594689Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2826,"took":"210.453294ms","hash":3442345172,"current-db-size-bytes":54452224,"current-db-size":"54 MB","current-db-size-in-use-bytes":29011968,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-14T22:59:59.594731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3442345172,"revision":2826,"compact-revision":-1} {"level":"info","ts":"2026-07-14T23:00:10.671026Z","caller":"traceutil/trace.go:171","msg":"trace[280764308] linearizableReadLoop","detail":"{readStateIndex:5758; appliedIndex:5757; }","duration":"124.313226ms","start":"2026-07-14T23:00:10.54669Z","end":"2026-07-14T23:00:10.671003Z","steps":["trace[280764308] 'read index received' (duration: 116.113526ms)","trace[280764308] 'applied index is now lower than readState.Index' (duration: 8.19848ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:00:10.671083Z","caller":"traceutil/trace.go:171","msg":"trace[2118498630] transaction","detail":"{read_only:false; response_revision:5546; number_of_response:1; }","duration":"144.877716ms","start":"2026-07-14T23:00:10.526176Z","end":"2026-07-14T23:00:10.671054Z","steps":["trace[2118498630] 'process raft request' (duration: 136.623195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:00:10.671175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.463079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:00:10.671232Z","caller":"traceutil/trace.go:171","msg":"trace[2055921917] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:5546; }","duration":"124.570481ms","start":"2026-07-14T23:00:10.546647Z","end":"2026-07-14T23:00:10.671218Z","steps":["trace[2055921917] 'agreement among raft nodes before linearized reading' (duration: 124.457739ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:00:26.836844Z","caller":"traceutil/trace.go:171","msg":"trace[1933495031] transaction","detail":"{read_only:false; response_revision:5662; number_of_response:1; }","duration":"102.979967ms","start":"2026-07-14T23:00:26.733839Z","end":"2026-07-14T23:00:26.836818Z","steps":["trace[1933495031] 'process raft request' (duration: 74.85976ms)","trace[1933495031] 'compare' (duration: 28.002934ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:00:26.873046Z","caller":"traceutil/trace.go:171","msg":"trace[171912887] linearizableReadLoop","detail":"{readStateIndex:5879; appliedIndex:5876; }","duration":"112.772824ms","start":"2026-07-14T23:00:26.760237Z","end":"2026-07-14T23:00:26.87301Z","steps":["trace[171912887] 'read index received' (duration: 48.475136ms)","trace[171912887] 'applied index is now lower than readState.Index' (duration: 64.296168ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:00:26.873484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.238882ms","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-14T23:00:26.873566Z","caller":"traceutil/trace.go:171","msg":"trace[1489897161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5664; }","duration":"113.362075ms","start":"2026-07-14T23:00:26.760176Z","end":"2026-07-14T23:00:26.873538Z","steps":["trace[1489897161] 'agreement among raft nodes before linearized reading' (duration: 113.161031ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:00:26.873743Z","caller":"traceutil/trace.go:171","msg":"trace[1883847618] transaction","detail":"{read_only:false; response_revision:5664; number_of_response:1; }","duration":"129.082055ms","start":"2026-07-14T23:00:26.744635Z","end":"2026-07-14T23:00:26.873717Z","steps":["trace[1883847618] 'process raft request' (duration: 128.314191ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:00:26.874271Z","caller":"traceutil/trace.go:171","msg":"trace[903689696] transaction","detail":"{read_only:false; response_revision:5663; number_of_response:1; }","duration":"130.780498ms","start":"2026-07-14T23:00:26.742431Z","end":"2026-07-14T23:00:26.873211Z","steps":["trace[903689696] 'process raft request' (duration: 130.315349ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:00:27.375797Z","caller":"traceutil/trace.go:171","msg":"trace[1754685397] transaction","detail":"{read_only:false; response_revision:5668; number_of_response:1; }","duration":"166.054798ms","start":"2026-07-14T23:00:27.20972Z","end":"2026-07-14T23:00:27.375775Z","steps":["trace[1754685397] 'process raft request' (duration: 165.907426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:00:27.53084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.83652ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:00:27.530888Z","caller":"traceutil/trace.go:171","msg":"trace[1925642489] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5668; }","duration":"116.894211ms","start":"2026-07-14T23:00:27.413982Z","end":"2026-07-14T23:00:27.530876Z","steps":["trace[1925642489] 'range keys from in-memory index tree' (duration: 116.824359ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:00:27.531317Z","caller":"traceutil/trace.go:171","msg":"trace[1860795207] linearizableReadLoop","detail":"{readStateIndex:5884; appliedIndex:5883; }","duration":"140.368308ms","start":"2026-07-14T23:00:27.390913Z","end":"2026-07-14T23:00:27.531282Z","steps":["trace[1860795207] 'read index received' (duration: 107.209075ms)","trace[1860795207] 'applied index is now lower than readState.Index' (duration: 33.158203ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:00:27.531365Z","caller":"traceutil/trace.go:171","msg":"trace[2123225634] transaction","detail":"{read_only:false; response_revision:5669; number_of_response:1; }","duration":"148.85416ms","start":"2026-07-14T23:00:27.38246Z","end":"2026-07-14T23:00:27.531314Z","steps":["trace[2123225634] 'process raft request' (duration: 115.736188ms)","trace[2123225634] 'compare' (duration: 32.928198ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:00:27.53166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.715595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-14T23:00:27.531708Z","caller":"traceutil/trace.go:171","msg":"trace[494900432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5669; }","duration":"140.824747ms","start":"2026-07-14T23:00:27.390871Z","end":"2026-07-14T23:00:27.531696Z","steps":["trace[494900432] 'agreement among raft nodes before linearized reading' (duration: 140.517601ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:00:27.709533Z","caller":"traceutil/trace.go:171","msg":"trace[1414606842] transaction","detail":"{read_only:false; response_revision:5670; number_of_response:1; }","duration":"174.590341ms","start":"2026-07-14T23:00:27.534911Z","end":"2026-07-14T23:00:27.709501Z","steps":["trace[1414606842] 'process raft request' (duration: 116.283148ms)","trace[1414606842] 'compare' (duration: 58.203391ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:00:27.772171Z","caller":"traceutil/trace.go:171","msg":"trace[2014617223] transaction","detail":"{read_only:false; response_revision:5671; number_of_response:1; }","duration":"232.921704ms","start":"2026-07-14T23:00:27.539223Z","end":"2026-07-14T23:00:27.772144Z","steps":["trace[2014617223] 'process raft request' (duration: 232.74109ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:00:28.01174Z","caller":"traceutil/trace.go:171","msg":"trace[676117885] transaction","detail":"{read_only:false; response_revision:5676; number_of_response:1; }","duration":"123.22571ms","start":"2026-07-14T23:00:27.888492Z","end":"2026-07-14T23:00:28.011717Z","steps":["trace[676117885] 'process raft request' (duration: 123.063427ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:00:28.142103Z","caller":"traceutil/trace.go:171","msg":"trace[350377388] linearizableReadLoop","detail":"{readStateIndex:5892; appliedIndex:5891; }","duration":"124.970792ms","start":"2026-07-14T23:00:28.017108Z","end":"2026-07-14T23:00:28.142079Z","steps":["trace[350377388] 'read index received' (duration: 81.448753ms)","trace[350377388] 'applied index is now lower than readState.Index' (duration: 43.520989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:00:28.142389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.159595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:00:28.142445Z","caller":"traceutil/trace.go:171","msg":"trace[1928732030] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:5676; }","duration":"125.357489ms","start":"2026-07-14T23:00:28.017057Z","end":"2026-07-14T23:00:28.142414Z","steps":["trace[1928732030] 'agreement among raft nodes before linearized reading' (duration: 125.146075ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:00:28.286238Z","caller":"traceutil/trace.go:171","msg":"trace[1058951244] transaction","detail":"{read_only:false; response_revision:5677; number_of_response:1; }","duration":"140.926466ms","start":"2026-07-14T23:00:28.145287Z","end":"2026-07-14T23:00:28.286213Z","steps":["trace[1058951244] 'process raft request' (duration: 140.767933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:01:28.188064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.352507ms","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-14T23:01:28.188149Z","caller":"traceutil/trace.go:171","msg":"trace[72897837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5990; }","duration":"128.48027ms","start":"2026-07-14T23:01:28.05965Z","end":"2026-07-14T23:01:28.18813Z","steps":["trace[72897837] 'agreement among raft nodes before linearized reading' (duration: 128.250535ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:01:33.666993Z","caller":"traceutil/trace.go:171","msg":"trace[554215104] linearizableReadLoop","detail":"{readStateIndex:6253; appliedIndex:6251; }","duration":"397.28005ms","start":"2026-07-14T23:01:33.269686Z","end":"2026-07-14T23:01:33.666966Z","steps":["trace[554215104] 'read index received' (duration: 5.003651ms)","trace[554215104] 'applied index is now lower than readState.Index' (duration: 392.274898ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:01:33.667176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.469293ms","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-14T23:01:33.667155Z","caller":"traceutil/trace.go:171","msg":"trace[692524640] transaction","detail":"{read_only:false; response_revision:6024; number_of_response:1; }","duration":"409.896967ms","start":"2026-07-14T23:01:33.257218Z","end":"2026-07-14T23:01:33.667115Z","steps":["trace[692524640] 'process raft request' (duration: 409.564651ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:01:33.667223Z","caller":"traceutil/trace.go:171","msg":"trace[398554540] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6024; }","duration":"397.549915ms","start":"2026-07-14T23:01:33.269658Z","end":"2026-07-14T23:01:33.667208Z","steps":["trace[398554540] 'agreement among raft nodes before linearized reading' (duration: 397.437623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:01:33.667551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:01:33.257201Z","time spent":"410.230114ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:01:33.667571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:01:33.269642Z","time spent":"397.768859ms","remote":"127.0.0.1:35750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-14T23:01:33.749765Z","caller":"traceutil/trace.go:171","msg":"trace[1234216794] transaction","detail":"{read_only:false; response_revision:6025; number_of_response:1; }","duration":"158.640747ms","start":"2026-07-14T23:01:33.591101Z","end":"2026-07-14T23:01:33.749742Z","steps":["trace[1234216794] 'process raft request' (duration: 153.189949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:01:33.750053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.841566ms","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-14T23:01:33.750174Z","caller":"traceutil/trace.go:171","msg":"trace[1162120574] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6025; }","duration":"452.024449ms","start":"2026-07-14T23:01:33.29813Z","end":"2026-07-14T23:01:33.750154Z","steps":["trace[1162120574] 'agreement among raft nodes before linearized reading' (duration: 451.607812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:01:33.750251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:01:33.298115Z","time spent":"452.122001ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T23:01:34.722103Z","caller":"traceutil/trace.go:171","msg":"trace[1388834800] transaction","detail":"{read_only:false; response_revision:6030; number_of_response:1; }","duration":"132.484851ms","start":"2026-07-14T23:01:34.589569Z","end":"2026-07-14T23:01:34.722054Z","steps":["trace[1388834800] 'process raft request' (duration: 96.571163ms)","trace[1388834800] 'compare' (duration: 35.720105ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:01:59.89693Z","caller":"traceutil/trace.go:171","msg":"trace[981435749] transaction","detail":"{read_only:false; response_revision:6190; number_of_response:1; }","duration":"110.915105ms","start":"2026-07-14T23:01:59.785991Z","end":"2026-07-14T23:01:59.896906Z","steps":["trace[981435749] 'process raft request' (duration: 73.822764ms)","trace[981435749] 'compare' (duration: 36.922088ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:02:04.735541Z","caller":"traceutil/trace.go:171","msg":"trace[842395398] linearizableReadLoop","detail":"{readStateIndex:6449; appliedIndex:6448; }","duration":"364.703365ms","start":"2026-07-14T23:02:04.370817Z","end":"2026-07-14T23:02:04.735521Z","steps":["trace[842395398] 'read index received' (duration: 364.476981ms)","trace[842395398] 'applied index is now lower than readState.Index' (duration: 225.484µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:02:04.735715Z","caller":"traceutil/trace.go:171","msg":"trace[591298327] transaction","detail":"{read_only:false; response_revision:6213; number_of_response:1; }","duration":"424.990435ms","start":"2026-07-14T23:02:04.310695Z","end":"2026-07-14T23:02:04.735685Z","steps":["trace[591298327] 'process raft request' (duration: 424.666499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:04.735833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:04.310676Z","time spent":"425.085547ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:02:04.735932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.120832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T23:02:04.736477Z","caller":"traceutil/trace.go:171","msg":"trace[633376549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6213; }","duration":"365.714393ms","start":"2026-07-14T23:02:04.370748Z","end":"2026-07-14T23:02:04.736462Z","steps":["trace[633376549] 'agreement among raft nodes before linearized reading' (duration: 364.99659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:04.736582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:04.370714Z","time spent":"365.847266ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T23:02:04.735998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.780969ms","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-14T23:02:04.736754Z","caller":"traceutil/trace.go:171","msg":"trace[1387546543] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:6213; }","duration":"168.510961ms","start":"2026-07-14T23:02:04.568205Z","end":"2026-07-14T23:02:04.736716Z","steps":["trace[1387546543] 'agreement among raft nodes before linearized reading' (duration: 167.751098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:04.735955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.80389ms","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:7"} {"level":"info","ts":"2026-07-14T23:02:04.736929Z","caller":"traceutil/trace.go:171","msg":"trace[1745226255] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:6213; }","duration":"283.833339ms","start":"2026-07-14T23:02:04.453074Z","end":"2026-07-14T23:02:04.736907Z","steps":["trace[1745226255] 'agreement among raft nodes before linearized reading' (duration: 282.76554ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:35.064531Z","caller":"traceutil/trace.go:171","msg":"trace[325500193] transaction","detail":"{read_only:false; response_revision:6529; number_of_response:1; }","duration":"110.089007ms","start":"2026-07-14T23:02:34.954421Z","end":"2026-07-14T23:02:35.06451Z","steps":["trace[325500193] 'process raft request' (duration: 27.050963ms)","trace[325500193] 'compare' (duration: 82.939522ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:02:35.862827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.428193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-07-14T23:02:35.862894Z","caller":"traceutil/trace.go:171","msg":"trace[471481019] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6533; }","duration":"122.538565ms","start":"2026-07-14T23:02:35.740341Z","end":"2026-07-14T23:02:35.862879Z","steps":["trace[471481019] 'range keys from in-memory index tree' (duration: 122.094848ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:47.57384Z","caller":"traceutil/trace.go:171","msg":"trace[1468339820] transaction","detail":"{read_only:false; response_revision:6597; number_of_response:1; }","duration":"109.386466ms","start":"2026-07-14T23:02:47.464433Z","end":"2026-07-14T23:02:47.573819Z","steps":["trace[1468339820] 'process raft request' (duration: 82.63547ms)","trace[1468339820] 'compare' (duration: 26.669785ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:02:48.446423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.55054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:02:48.44649Z","caller":"traceutil/trace.go:171","msg":"trace[378611835] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:6600; }","duration":"112.667852ms","start":"2026-07-14T23:02:48.333809Z","end":"2026-07-14T23:02:48.446477Z","steps":["trace[378611835] 'count revisions from in-memory index tree' (duration: 112.431328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:49.135734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.687588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:02:49.135802Z","caller":"traceutil/trace.go:171","msg":"trace[114935324] linearizableReadLoop","detail":"{readStateIndex:6851; appliedIndex:6850; }","duration":"180.506682ms","start":"2026-07-14T23:02:48.955285Z","end":"2026-07-14T23:02:49.135791Z","steps":["trace[114935324] 'read index received' (duration: 27.800744ms)","trace[114935324] 'applied index is now lower than readState.Index' (duration: 152.705088ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:02:49.135901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.616624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-14T23:02:49.135932Z","caller":"traceutil/trace.go:171","msg":"trace[1514314086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6602; }","duration":"180.663495ms","start":"2026-07-14T23:02:48.955257Z","end":"2026-07-14T23:02:49.135921Z","steps":["trace[1514314086] 'agreement among raft nodes before linearized reading' (duration: 180.563644ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:49.135953Z","caller":"traceutil/trace.go:171","msg":"trace[184621597] transaction","detail":"{read_only:false; response_revision:6602; number_of_response:1; }","duration":"188.890798ms","start":"2026-07-14T23:02:48.947029Z","end":"2026-07-14T23:02:49.13592Z","steps":["trace[184621597] 'process raft request' (duration: 35.952606ms)","trace[184621597] 'compare' (duration: 152.406203ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:02:49.283122Z","caller":"traceutil/trace.go:171","msg":"trace[2134111908] transaction","detail":"{read_only:false; response_revision:6603; number_of_response:1; }","duration":"155.006158ms","start":"2026-07-14T23:02:49.12809Z","end":"2026-07-14T23:02:49.283096Z","steps":["trace[2134111908] 'process raft request' (duration: 121.979074ms)","trace[2134111908] 'compare' (duration: 32.867342ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:02:49.283387Z","caller":"traceutil/trace.go:171","msg":"trace[1791201598] linearizableReadLoop","detail":"{readStateIndex:6853; appliedIndex:6851; }","duration":"112.784863ms","start":"2026-07-14T23:02:49.170589Z","end":"2026-07-14T23:02:49.283374Z","steps":["trace[1791201598] 'read index received' (duration: 79.476764ms)","trace[1791201598] 'applied index is now lower than readState.Index' (duration: 33.307069ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:02:49.28353Z","caller":"traceutil/trace.go:171","msg":"trace[1891898637] transaction","detail":"{read_only:false; response_revision:6604; number_of_response:1; }","duration":"144.359683ms","start":"2026-07-14T23:02:49.139161Z","end":"2026-07-14T23:02:49.283521Z","steps":["trace[1891898637] 'process raft request' (duration: 143.896125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:49.284432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.829791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:02:49.284492Z","caller":"traceutil/trace.go:171","msg":"trace[156715867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6604; }","duration":"113.925543ms","start":"2026-07-14T23:02:49.170551Z","end":"2026-07-14T23:02:49.284477Z","steps":["trace[156715867] 'agreement among raft nodes before linearized reading' (duration: 113.054198ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:59.787352Z","caller":"traceutil/trace.go:171","msg":"trace[1863737189] transaction","detail":"{read_only:false; response_revision:6711; number_of_response:1; }","duration":"172.841868ms","start":"2026-07-14T23:02:59.614492Z","end":"2026-07-14T23:02:59.787334Z","steps":["trace[1863737189] 'process raft request' (duration: 172.753917ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:09.497463Z","caller":"traceutil/trace.go:171","msg":"trace[471749127] linearizableReadLoop","detail":"{readStateIndex:7011; appliedIndex:7010; }","duration":"133.435507ms","start":"2026-07-14T23:03:09.364008Z","end":"2026-07-14T23:03:09.497444Z","steps":["trace[471749127] 'read index received' (duration: 110.600572ms)","trace[471749127] 'applied index is now lower than readState.Index' (duration: 22.834145ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:09.497571Z","caller":"traceutil/trace.go:171","msg":"trace[653849834] transaction","detail":"{read_only:false; response_revision:6756; number_of_response:1; }","duration":"146.89641ms","start":"2026-07-14T23:03:09.350666Z","end":"2026-07-14T23:03:09.497562Z","steps":["trace[653849834] 'process raft request' (duration: 123.992544ms)","trace[653849834] 'compare' (duration: 22.690242ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:09.497674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.240043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-14T23:03:09.497726Z","caller":"traceutil/trace.go:171","msg":"trace[1154779155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6756; }","duration":"133.351755ms","start":"2026-07-14T23:03:09.364363Z","end":"2026-07-14T23:03:09.497714Z","steps":["trace[1154779155] 'agreement among raft nodes before linearized reading' (duration: 133.219473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:09.497795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.801904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1982"} {"level":"info","ts":"2026-07-14T23:03:09.497808Z","caller":"traceutil/trace.go:171","msg":"trace[740917702] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:6756; }","duration":"133.863825ms","start":"2026-07-14T23:03:09.363939Z","end":"2026-07-14T23:03:09.497803Z","steps":["trace[740917702] 'agreement among raft nodes before linearized reading' (duration: 133.820114ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:12.455858Z","caller":"traceutil/trace.go:171","msg":"trace[1473876283] transaction","detail":"{read_only:false; response_revision:6780; number_of_response:1; }","duration":"189.157208ms","start":"2026-07-14T23:03:12.266678Z","end":"2026-07-14T23:03:12.455835Z","steps":["trace[1473876283] 'process raft request' (duration: 188.749141ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:12.686652Z","caller":"traceutil/trace.go:171","msg":"trace[1084681374] linearizableReadLoop","detail":"{readStateIndex:7036; appliedIndex:7035; }","duration":"219.754667ms","start":"2026-07-14T23:03:12.466876Z","end":"2026-07-14T23:03:12.686631Z","steps":["trace[1084681374] 'read index received' (duration: 186.203927ms)","trace[1084681374] 'applied index is now lower than readState.Index' (duration: 33.54973ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:12.687105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.215264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com\" ","response":"range_response_count:1 size:120324"} {"level":"info","ts":"2026-07-14T23:03:12.687122Z","caller":"traceutil/trace.go:171","msg":"trace[2000909256] transaction","detail":"{read_only:false; response_revision:6781; number_of_response:1; }","duration":"221.519277ms","start":"2026-07-14T23:03:12.465576Z","end":"2026-07-14T23:03:12.687095Z","steps":["trace[2000909256] 'process raft request' (duration: 187.529769ms)","trace[2000909256] 'compare' (duration: 33.266795ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:12.687169Z","caller":"traceutil/trace.go:171","msg":"trace[650760709] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com; range_end:; response_count:1; response_revision:6781; }","duration":"220.320506ms","start":"2026-07-14T23:03:12.466833Z","end":"2026-07-14T23:03:12.687154Z","steps":["trace[650760709] 'agreement among raft nodes before linearized reading' (duration: 220.122872ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:12.900415Z","caller":"traceutil/trace.go:171","msg":"trace[565934574] linearizableReadLoop","detail":"{readStateIndex:7037; appliedIndex:7036; }","duration":"182.076215ms","start":"2026-07-14T23:03:12.718317Z","end":"2026-07-14T23:03:12.900393Z","steps":["trace[565934574] 'read index received' (duration: 176.61466ms)","trace[565934574] 'applied index is now lower than readState.Index' (duration: 5.460075ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:12.900734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.39463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com\" ","response":"range_response_count:1 size:120324"} {"level":"info","ts":"2026-07-14T23:03:12.900768Z","caller":"traceutil/trace.go:171","msg":"trace[1032341779] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com; range_end:; response_count:1; response_revision:6781; }","duration":"182.481752ms","start":"2026-07-14T23:03:12.718278Z","end":"2026-07-14T23:03:12.900759Z","steps":["trace[1032341779] 'agreement among raft nodes before linearized reading' (duration: 182.190717ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:59.388157Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5462} {"level":"info","ts":"2026-07-14T23:04:59.513957Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5462,"took":"124.416067ms","hash":86536294,"current-db-size-bytes":54452224,"current-db-size":"54 MB","current-db-size-in-use-bytes":18571264,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-14T23:04:59.514028Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":86536294,"revision":5462,"compact-revision":2826}