{"level":"warn","ts":"2026-09-10T10:59:58.184036Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T10:59:58.184143Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.121: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://162.253.55.121:2380","--initial-cluster=instance=https://162.253.55.121:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.121:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.121: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-09-10T10:59:58.184258Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T10:59:58.184271Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.121:2380"]} {"level":"info","ts":"2026-09-10T10:59:58.184289Z","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-09-10T10:59:58.184875Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.121:2379"]} {"level":"info","ts":"2026-09-10T10:59:58.184955Z","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://162.253.55.121:2380"],"listen-peer-urls":["https://162.253.55.121:2380"],"advertise-client-urls":["https://162.253.55.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.121: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-09-10T10:59:58.19248Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.268649ms"} {"level":"info","ts":"2026-09-10T10:59:58.199918Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e064f6438c9a029d","cluster-id":"46d59d02273d6950"} {"level":"info","ts":"2026-09-10T10:59:58.199978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T10:59:58.199997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d became follower at term 0"} {"level":"info","ts":"2026-09-10T10:59:58.200119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e064f6438c9a029d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T10:59:58.200139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d became follower at term 1"} {"level":"info","ts":"2026-09-10T10:59:58.200203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d switched to configuration voters=(16169319332149068445)"} {"level":"warn","ts":"2026-09-10T10:59:58.214073Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T10:59:58.215863Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T10:59:58.21713Z","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-09-10T10:59:58.219582Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e064f6438c9a029d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T10:59:58.222851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d switched to configuration voters=(16169319332149068445)"} {"level":"info","ts":"2026-09-10T10:59:58.220387Z","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-09-10T10:59:58.223029Z","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-09-10T10:59:58.223053Z","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-09-10T10:59:58.220309Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e064f6438c9a029d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T10:59:58.223074Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"46d59d02273d6950","local-member-id":"e064f6438c9a029d","added-peer-id":"e064f6438c9a029d","added-peer-peer-urls":["https://162.253.55.121:2380"]} {"level":"info","ts":"2026-09-10T10:59:58.228263Z","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-09-10T10:59:58.228486Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.121:2380"} {"level":"info","ts":"2026-09-10T10:59:58.228531Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.121:2380"} {"level":"info","ts":"2026-09-10T10:59:58.228573Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e064f6438c9a029d","initial-advertise-peer-urls":["https://162.253.55.121:2380"],"listen-peer-urls":["https://162.253.55.121:2380"],"advertise-client-urls":["https://162.253.55.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T10:59:58.228744Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T10:59:58.401124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T10:59:58.401232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T10:59:58.401333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d received MsgPreVoteResp from e064f6438c9a029d at term 1"} {"level":"info","ts":"2026-09-10T10:59:58.401398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d became candidate at term 2"} {"level":"info","ts":"2026-09-10T10:59:58.40142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d received MsgVoteResp from e064f6438c9a029d at term 2"} {"level":"info","ts":"2026-09-10T10:59:58.401467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d became leader at term 2"} {"level":"info","ts":"2026-09-10T10:59:58.401488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e064f6438c9a029d elected leader e064f6438c9a029d at term 2"} {"level":"info","ts":"2026-09-10T10:59:58.402985Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e064f6438c9a029d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.121:2379]}","request-path":"/0/members/e064f6438c9a029d/attributes","cluster-id":"46d59d02273d6950","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T10:59:58.403274Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T10:59:58.403491Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T10:59:58.403877Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T10:59:58.404226Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T10:59:58.404268Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T10:59:58.404938Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"46d59d02273d6950","local-member-id":"e064f6438c9a029d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T10:59:58.405122Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T10:59:58.405219Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T10:59:58.405683Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.121:2379"} {"level":"info","ts":"2026-09-10T10:59:58.408531Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-10T11:00:01.480835Z","caller":"traceutil/trace.go:171","msg":"trace[1938431288] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"149.754786ms","start":"2026-09-10T11:00:01.331053Z","end":"2026-09-10T11:00:01.480808Z","steps":["trace[1938431288] 'process raft request' (duration: 83.3643ms)","trace[1938431288] 'compare' (duration: 66.248568ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:00:42.547805Z","caller":"traceutil/trace.go:171","msg":"trace[678965356] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"259.905162ms","start":"2026-09-10T11:00:42.287854Z","end":"2026-09-10T11:00:42.547759Z","steps":["trace[678965356] 'process raft request' (duration: 259.758876ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:42.558815Z","caller":"traceutil/trace.go:171","msg":"trace[1757808393] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"267.505415ms","start":"2026-09-10T11:00:42.291289Z","end":"2026-09-10T11:00:42.558794Z","steps":["trace[1757808393] 'process raft request' (duration: 266.601872ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:42.558975Z","caller":"traceutil/trace.go:171","msg":"trace[26039831] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"254.346183ms","start":"2026-09-10T11:00:42.304612Z","end":"2026-09-10T11:00:42.558959Z","steps":["trace[26039831] 'process raft request' (duration: 254.131555ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:42.559105Z","caller":"traceutil/trace.go:171","msg":"trace[1548751149] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"265.955129ms","start":"2026-09-10T11:00:42.293126Z","end":"2026-09-10T11:00:42.559081Z","steps":["trace[1548751149] 'process raft request' (duration: 265.549404ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:42.559116Z","caller":"traceutil/trace.go:171","msg":"trace[1311985880] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"254.407635ms","start":"2026-09-10T11:00:42.30469Z","end":"2026-09-10T11:00:42.559098Z","steps":["trace[1311985880] 'process raft request' (duration: 254.103354ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:42.559175Z","caller":"traceutil/trace.go:171","msg":"trace[323378596] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"254.27295ms","start":"2026-09-10T11:00:42.304834Z","end":"2026-09-10T11:00:42.559107Z","steps":["trace[323378596] 'process raft request' (duration: 254.014121ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:42.5592Z","caller":"traceutil/trace.go:171","msg":"trace[299163998] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"247.244478ms","start":"2026-09-10T11:00:42.311948Z","end":"2026-09-10T11:00:42.559192Z","steps":["trace[299163998] 'process raft request' (duration: 247.003679ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:42.559342Z","caller":"traceutil/trace.go:171","msg":"trace[660427577] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"233.800166ms","start":"2026-09-10T11:00:42.325533Z","end":"2026-09-10T11:00:42.559333Z","steps":["trace[660427577] 'process raft request' (duration: 233.580378ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:42.559474Z","caller":"traceutil/trace.go:171","msg":"trace[1503190541] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"219.864246ms","start":"2026-09-10T11:00:42.339602Z","end":"2026-09-10T11:00:42.559467Z","steps":["trace[1503190541] 'process raft request' (duration: 219.631417ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:42.559617Z","caller":"traceutil/trace.go:171","msg":"trace[1661681430] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"207.078347ms","start":"2026-09-10T11:00:42.352525Z","end":"2026-09-10T11:00:42.559604Z","steps":["trace[1661681430] 'process raft request' (duration: 206.825088ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:42.559743Z","caller":"traceutil/trace.go:171","msg":"trace[370657616] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"192.522416ms","start":"2026-09-10T11:00:42.367209Z","end":"2026-09-10T11:00:42.559732Z","steps":["trace[370657616] 'process raft request' (duration: 192.302708ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:42.55982Z","caller":"traceutil/trace.go:171","msg":"trace[1434545463] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:526; }","duration":"174.716567ms","start":"2026-09-10T11:00:42.385078Z","end":"2026-09-10T11:00:42.559795Z","steps":["trace[1434545463] 'read index received' (duration: 162.490638ms)","trace[1434545463] 'applied index is now lower than readState.Index' (duration: 12.224739ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:00:42.560079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.974886ms","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-09-10T11:00:42.560134Z","caller":"traceutil/trace.go:171","msg":"trace[1816321325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"175.080151ms","start":"2026-09-10T11:00:42.385041Z","end":"2026-09-10T11:00:42.560121Z","steps":["trace[1816321325] 'agreement among raft nodes before linearized reading' (duration: 174.870733ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:48.697092Z","caller":"traceutil/trace.go:171","msg":"trace[116550028] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"246.01002ms","start":"2026-09-10T11:00:48.450999Z","end":"2026-09-10T11:00:48.697009Z","steps":["trace[116550028] 'process raft request' (duration: 245.257406ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:49.088723Z","caller":"traceutil/trace.go:171","msg":"trace[1981426733] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"319.940599ms","start":"2026-09-10T11:00:48.768743Z","end":"2026-09-10T11:00:49.088684Z","steps":["trace[1981426733] 'process raft request' (duration: 250.868448ms)","trace[1981426733] 'compare' (duration: 68.103488ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:00:49.088951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.492547ms","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-09-10T11:00:49.089023Z","caller":"traceutil/trace.go:171","msg":"trace[1849829838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"304.615041ms","start":"2026-09-10T11:00:48.784388Z","end":"2026-09-10T11:00:49.089003Z","steps":["trace[1849829838] 'agreement among raft nodes before linearized reading' (duration: 304.390014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:49.089122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:00:48.78436Z","time spent":"304.736855ms","remote":"127.0.0.1:58368","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":"info","ts":"2026-09-10T11:00:49.087997Z","caller":"traceutil/trace.go:171","msg":"trace[806204932] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"303.500924ms","start":"2026-09-10T11:00:48.784441Z","end":"2026-09-10T11:00:49.087942Z","steps":["trace[806204932] 'read index received' (duration: 234.993922ms)","trace[806204932] 'applied index is now lower than readState.Index' (duration: 68.505342ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:00:49.089885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:00:48.768718Z","time spent":"320.103225ms","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T11:00:49.090794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.37353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:00:49.09087Z","caller":"traceutil/trace.go:171","msg":"trace[1667690308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"110.502075ms","start":"2026-09-10T11:00:48.980345Z","end":"2026-09-10T11:00:49.090847Z","steps":["trace[1667690308] 'agreement among raft nodes before linearized reading' (duration: 110.3763ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:49.82639Z","caller":"traceutil/trace.go:171","msg":"trace[1048582705] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"286.357519ms","start":"2026-09-10T11:00:49.540001Z","end":"2026-09-10T11:00:49.826359Z","steps":["trace[1048582705] 'process raft request' (duration: 285.711097ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:50.394593Z","caller":"traceutil/trace.go:171","msg":"trace[325260088] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"415.181052ms","start":"2026-09-10T11:00:49.97938Z","end":"2026-09-10T11:00:50.394561Z","steps":["trace[325260088] 'read index received' (duration: 414.971565ms)","trace[325260088] 'applied index is now lower than readState.Index' (duration: 208.327µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:00:50.394884Z","caller":"traceutil/trace.go:171","msg":"trace[1163256747] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"510.2031ms","start":"2026-09-10T11:00:49.884663Z","end":"2026-09-10T11:00:50.394866Z","steps":["trace[1163256747] 'process raft request' (duration: 509.746975ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:50.39525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.083744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-10T11:00:50.395429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.052961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-10T11:00:50.395261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:00:49.884635Z","time spent":"510.292113ms","remote":"127.0.0.1:58334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T11:00:50.395508Z","caller":"traceutil/trace.go:171","msg":"trace[15911507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"416.163585ms","start":"2026-09-10T11:00:49.979326Z","end":"2026-09-10T11:00:50.39549Z","steps":["trace[15911507] 'agreement among raft nodes before linearized reading' (duration: 415.799322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:50.395297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.844107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jhprw\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-10T11:00:50.395402Z","caller":"traceutil/trace.go:171","msg":"trace[46817745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"414.298841ms","start":"2026-09-10T11:00:49.981083Z","end":"2026-09-10T11:00:50.395382Z","steps":["trace[46817745] 'agreement among raft nodes before linearized reading' (duration: 413.918458ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:50.395637Z","caller":"traceutil/trace.go:171","msg":"trace[2018866211] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jhprw; range_end:; response_count:1; response_revision:618; }","duration":"151.176858ms","start":"2026-09-10T11:00:50.24438Z","end":"2026-09-10T11:00:50.395557Z","steps":["trace[2018866211] 'agreement among raft nodes before linearized reading' (duration: 150.757504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:50.395602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:00:49.979277Z","time spent":"416.306859ms","remote":"127.0.0.1:58368","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-09-10T11:00:50.395757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:00:49.981076Z","time spent":"414.55911ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-10T11:01:07.650927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.487741ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-10T11:01:07.651131Z","caller":"traceutil/trace.go:171","msg":"trace[1602184006] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"298.2532ms","start":"2026-09-10T11:01:07.352826Z","end":"2026-09-10T11:01:07.651079Z","steps":["trace[1602184006] 'read index received' (duration: 48.461µs)","trace[1602184006] 'applied index is now lower than readState.Index' (duration: 298.202229ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:01:07.651399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.502918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-r8tn8\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-10T11:01:07.651457Z","caller":"traceutil/trace.go:171","msg":"trace[1351814170] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-r8tn8; range_end:; response_count:1; response_revision:785; }","duration":"298.674512ms","start":"2026-09-10T11:01:07.352767Z","end":"2026-09-10T11:01:07.651442Z","steps":["trace[1351814170] 'agreement among raft nodes before linearized reading' (duration: 298.395394ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:40.248744Z","caller":"traceutil/trace.go:171","msg":"trace[689974010] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"147.824823ms","start":"2026-09-10T11:01:40.100888Z","end":"2026-09-10T11:01:40.248713Z","steps":["trace[689974010] 'process raft request' (duration: 147.599238ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:16.979138Z","caller":"traceutil/trace.go:171","msg":"trace[47053196] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"133.778106ms","start":"2026-09-10T11:02:16.845344Z","end":"2026-09-10T11:02:16.979122Z","steps":["trace[47053196] 'process raft request' (duration: 129.128463ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:16.979288Z","caller":"traceutil/trace.go:171","msg":"trace[773954375] linearizableReadLoop","detail":"{readStateIndex:1761; appliedIndex:1760; }","duration":"102.272969ms","start":"2026-09-10T11:02:16.877Z","end":"2026-09-10T11:02:16.979273Z","steps":["trace[773954375] 'read index received' (duration: 97.539824ms)","trace[773954375] 'applied index is now lower than readState.Index' (duration: 4.731405ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:02:16.979657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.635545ms","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-09-10T11:02:16.979711Z","caller":"traceutil/trace.go:171","msg":"trace[634414480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1694; }","duration":"102.725518ms","start":"2026-09-10T11:02:16.876973Z","end":"2026-09-10T11:02:16.979699Z","steps":["trace[634414480] 'agreement among raft nodes before linearized reading' (duration: 102.341621ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:30.857146Z","caller":"traceutil/trace.go:171","msg":"trace[1534073693] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"120.9812ms","start":"2026-09-10T11:02:30.736141Z","end":"2026-09-10T11:02:30.857122Z","steps":["trace[1534073693] 'read index received' (duration: 48.117586ms)","trace[1534073693] 'applied index is now lower than readState.Index' (duration: 72.862694ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:30.857223Z","caller":"traceutil/trace.go:171","msg":"trace[2043402538] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"156.755585ms","start":"2026-09-10T11:02:30.700446Z","end":"2026-09-10T11:02:30.857201Z","steps":["trace[2043402538] 'process raft request' (duration: 83.861631ms)","trace[2043402538] 'compare' (duration: 72.505068ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:02:30.857345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.180513ms","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-09-10T11:02:30.85738Z","caller":"traceutil/trace.go:171","msg":"trace[51105181] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1870; }","duration":"121.231704ms","start":"2026-09-10T11:02:30.736136Z","end":"2026-09-10T11:02:30.857368Z","steps":["trace[51105181] 'agreement among raft nodes before linearized reading' (duration: 121.152763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:30.857468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.43356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee774440-88f2-44f2-9bf7-78148249805b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-10T11:02:30.857499Z","caller":"traceutil/trace.go:171","msg":"trace[830047534] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ee774440-88f2-44f2-9bf7-78148249805b; range_end:; response_count:1; response_revision:1870; }","duration":"104.46625ms","start":"2026-09-10T11:02:30.753022Z","end":"2026-09-10T11:02:30.857488Z","steps":["trace[830047534] 'agreement among raft nodes before linearized reading' (duration: 104.329108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:31.689471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.574715ms","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-09-10T11:02:31.689511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.861664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:02:31.689642Z","caller":"traceutil/trace.go:171","msg":"trace[573532106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1871; }","duration":"710.092827ms","start":"2026-09-10T11:02:30.979509Z","end":"2026-09-10T11:02:31.689602Z","steps":["trace[573532106] 'range keys from in-memory index tree' (duration: 709.741102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:31.689707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:30.97949Z","time spent":"710.205308ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-10T11:02:31.689556Z","caller":"traceutil/trace.go:171","msg":"trace[1683490704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1871; }","duration":"639.725067ms","start":"2026-09-10T11:02:31.049811Z","end":"2026-09-10T11:02:31.689536Z","steps":["trace[1683490704] 'range keys from in-memory index tree' (duration: 639.390302ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:31.690012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.04979Z","time spent":"640.087573ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-10T11:02:31.690605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.894181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:02:31.690941Z","caller":"traceutil/trace.go:171","msg":"trace[1723386237] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"532.086111ms","start":"2026-09-10T11:02:31.158829Z","end":"2026-09-10T11:02:31.690915Z","steps":["trace[1723386237] 'process raft request' (duration: 531.968419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:31.691036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.158811Z","time spent":"532.180283ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:02:31.691052Z","caller":"traceutil/trace.go:171","msg":"trace[81275347] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1946; }","duration":"589.101804ms","start":"2026-09-10T11:02:31.101909Z","end":"2026-09-10T11:02:31.691011Z","steps":["trace[81275347] 'read index received' (duration: 195.4814ms)","trace[81275347] 'applied index is now lower than readState.Index' (duration: 393.617044ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:31.691213Z","caller":"traceutil/trace.go:171","msg":"trace[1799902805] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"590.227272ms","start":"2026-09-10T11:02:31.100969Z","end":"2026-09-10T11:02:31.691196Z","steps":["trace[1799902805] 'process raft request' (duration: 196.401725ms)","trace[1799902805] 'compare' (duration: 392.655697ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:02:31.691348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.100947Z","time spent":"590.304164ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:02:31.691649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.653649ms","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-09-10T11:02:31.691695Z","caller":"traceutil/trace.go:171","msg":"trace[2090078838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1873; }","duration":"433.772852ms","start":"2026-09-10T11:02:31.257909Z","end":"2026-09-10T11:02:31.691682Z","steps":["trace[2090078838] 'agreement among raft nodes before linearized reading' (duration: 433.591378ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:31.691729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.257879Z","time spent":"433.842563ms","remote":"127.0.0.1:58368","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-09-10T11:02:31.691864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.028791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T11:02:31.691925Z","caller":"traceutil/trace.go:171","msg":"trace[1163340224] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1873; }","duration":"272.165524ms","start":"2026-09-10T11:02:31.419745Z","end":"2026-09-10T11:02:31.69191Z","steps":["trace[1163340224] 'agreement among raft nodes before linearized reading' (duration: 272.020152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:31.691935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.882352ms","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-09-10T11:02:31.691976Z","caller":"traceutil/trace.go:171","msg":"trace[2082718618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1873; }","duration":"364.023874ms","start":"2026-09-10T11:02:31.327941Z","end":"2026-09-10T11:02:31.691965Z","steps":["trace[2082718618] 'agreement among raft nodes before linearized reading' (duration: 363.925053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:31.692259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.339845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee774440-88f2-44f2-9bf7-78148249805b\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-09-10T11:02:31.69232Z","caller":"traceutil/trace.go:171","msg":"trace[1324398810] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ee774440-88f2-44f2-9bf7-78148249805b; range_end:; response_count:1; response_revision:1873; }","duration":"590.426396ms","start":"2026-09-10T11:02:31.101879Z","end":"2026-09-10T11:02:31.692305Z","steps":["trace[1324398810] 'agreement among raft nodes before linearized reading' (duration: 590.240614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:31.692357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.101866Z","time spent":"590.480647ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3767,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee774440-88f2-44f2-9bf7-78148249805b\" "} {"level":"warn","ts":"2026-09-10T11:02:31.692003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.327901Z","time spent":"364.095506ms","remote":"127.0.0.1:58368","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-09-10T11:02:32.141746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.724907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:02:32.142247Z","caller":"traceutil/trace.go:171","msg":"trace[86389923] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"322.691698ms","start":"2026-09-10T11:02:31.819534Z","end":"2026-09-10T11:02:32.142225Z","steps":["trace[86389923] 'process raft request' (duration: 322.578606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:32.14238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.819506Z","time spent":"322.78876ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:02:32.142404Z","caller":"traceutil/trace.go:171","msg":"trace[1437671877] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"445.573473ms","start":"2026-09-10T11:02:31.696794Z","end":"2026-09-10T11:02:32.142368Z","steps":["trace[1437671877] 'process raft request' (duration: 445.172256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:32.143003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.69677Z","time spent":"446.137572ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:02:32.142608Z","caller":"traceutil/trace.go:171","msg":"trace[1881994934] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1949; }","duration":"357.004138ms","start":"2026-09-10T11:02:31.785583Z","end":"2026-09-10T11:02:32.142588Z","steps":["trace[1881994934] 'read index received' (duration: 250.330034ms)","trace[1881994934] 'applied index is now lower than readState.Index' (duration: 106.671454ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:32.142687Z","caller":"traceutil/trace.go:171","msg":"trace[933651639] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"446.240814ms","start":"2026-09-10T11:02:31.696393Z","end":"2026-09-10T11:02:32.142634Z","steps":["trace[933651639] 'process raft request' (duration: 339.52164ms)","trace[933651639] 'compare' (duration: 104.985555ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:02:32.143456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.696366Z","time spent":"446.999406ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:02:32.142818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.229092ms","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-09-10T11:02:32.144322Z","caller":"traceutil/trace.go:171","msg":"trace[115007105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1877; }","duration":"358.786597ms","start":"2026-09-10T11:02:31.785514Z","end":"2026-09-10T11:02:32.144301Z","steps":["trace[115007105] 'agreement among raft nodes before linearized reading' (duration: 357.15792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:32.144456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.785491Z","time spent":"358.94566ms","remote":"127.0.0.1:58368","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-09-10T11:02:32.143797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.078914ms","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-09-10T11:02:32.144719Z","caller":"traceutil/trace.go:171","msg":"trace[238918595] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1877; }","duration":"279.093762ms","start":"2026-09-10T11:02:31.865602Z","end":"2026-09-10T11:02:32.144696Z","steps":["trace[238918595] 'agreement among raft nodes before linearized reading' (duration: 278.007613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:32.143883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.366431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.121\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-10T11:02:32.144834Z","caller":"traceutil/trace.go:171","msg":"trace[1743569352] range","detail":"{range_begin:/registry/masterleases/162.253.55.121; range_end:; response_count:1; response_revision:1877; }","duration":"106.349078ms","start":"2026-09-10T11:02:32.038473Z","end":"2026-09-10T11:02:32.144822Z","steps":["trace[1743569352] 'agreement among raft nodes before linearized reading' (duration: 105.350711ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:44.324096Z","caller":"traceutil/trace.go:171","msg":"trace[41257344] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"106.762088ms","start":"2026-09-10T11:02:44.217304Z","end":"2026-09-10T11:02:44.324067Z","steps":["trace[41257344] 'process raft request' (duration: 106.554435ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:44.364588Z","caller":"traceutil/trace.go:171","msg":"trace[1728991675] linearizableReadLoop","detail":"{readStateIndex:2065; appliedIndex:2062; }","duration":"134.340985ms","start":"2026-09-10T11:02:44.230228Z","end":"2026-09-10T11:02:44.364569Z","steps":["trace[1728991675] 'read index received' (duration: 93.512947ms)","trace[1728991675] 'applied index is now lower than readState.Index' (duration: 40.827308ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:02:44.364746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.504487ms","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-09-10T11:02:44.364779Z","caller":"traceutil/trace.go:171","msg":"trace[1602243418] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1986; }","duration":"134.578008ms","start":"2026-09-10T11:02:44.230188Z","end":"2026-09-10T11:02:44.364766Z","steps":["trace[1602243418] 'agreement among raft nodes before linearized reading' (duration: 134.460676ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:44.364936Z","caller":"traceutil/trace.go:171","msg":"trace[1744547061] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"146.609623ms","start":"2026-09-10T11:02:44.218321Z","end":"2026-09-10T11:02:44.36493Z","steps":["trace[1744547061] 'process raft request' (duration: 146.076014ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:44.364959Z","caller":"traceutil/trace.go:171","msg":"trace[511561600] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"141.348398ms","start":"2026-09-10T11:02:44.223589Z","end":"2026-09-10T11:02:44.364937Z","steps":["trace[511561600] 'process raft request' (duration: 140.949011ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:45.255098Z","caller":"traceutil/trace.go:171","msg":"trace[368844248] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"350.541824ms","start":"2026-09-10T11:02:44.904531Z","end":"2026-09-10T11:02:45.255073Z","steps":["trace[368844248] 'process raft request' (duration: 335.872738ms)","trace[368844248] 'compare' (duration: 14.073786ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:45.255121Z","caller":"traceutil/trace.go:171","msg":"trace[310824872] linearizableReadLoop","detail":"{readStateIndex:2072; appliedIndex:2071; }","duration":"325.882087ms","start":"2026-09-10T11:02:44.9292Z","end":"2026-09-10T11:02:45.255082Z","steps":["trace[310824872] 'read index received' (duration: 311.216801ms)","trace[310824872] 'applied index is now lower than readState.Index' (duration: 14.661106ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:02:45.255245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:44.904509Z","time spent":"350.657625ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5868,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:02:45.255326Z","caller":"traceutil/trace.go:171","msg":"trace[1818614645] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"313.358644ms","start":"2026-09-10T11:02:44.94193Z","end":"2026-09-10T11:02:45.255289Z","steps":["trace[1818614645] 'process raft request' (duration: 313.07765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:45.255463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.229902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11986"} {"level":"warn","ts":"2026-09-10T11:02:45.255473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:44.941912Z","time spent":"313.485557ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:02:45.255535Z","caller":"traceutil/trace.go:171","msg":"trace[807187667] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1994; }","duration":"326.369375ms","start":"2026-09-10T11:02:44.929155Z","end":"2026-09-10T11:02:45.255524Z","steps":["trace[807187667] 'agreement among raft nodes before linearized reading' (duration: 326.08637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:45.255578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:44.929133Z","time spent":"326.430686ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12009,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-10T11:02:45.255762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.868042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-10T11:02:45.255743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.66755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:02:45.255816Z","caller":"traceutil/trace.go:171","msg":"trace[638663535] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1994; }","duration":"262.941803ms","start":"2026-09-10T11:02:44.992857Z","end":"2026-09-10T11:02:45.255798Z","steps":["trace[638663535] 'agreement among raft nodes before linearized reading' (duration: 262.854841ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:45.255829Z","caller":"traceutil/trace.go:171","msg":"trace[812936925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1994; }","duration":"277.805632ms","start":"2026-09-10T11:02:44.978005Z","end":"2026-09-10T11:02:45.25581Z","steps":["trace[812936925] 'agreement among raft nodes before linearized reading' (duration: 277.658049ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:45.961624Z","caller":"traceutil/trace.go:171","msg":"trace[1747650167] linearizableReadLoop","detail":"{readStateIndex:2080; appliedIndex:2079; }","duration":"139.493149ms","start":"2026-09-10T11:02:45.822107Z","end":"2026-09-10T11:02:45.9616Z","steps":["trace[1747650167] 'read index received' (duration: 139.229716ms)","trace[1747650167] 'applied index is now lower than readState.Index' (duration: 262.523µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:45.961658Z","caller":"traceutil/trace.go:171","msg":"trace[843219585] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"216.372424ms","start":"2026-09-10T11:02:45.745266Z","end":"2026-09-10T11:02:45.961638Z","steps":["trace[843219585] 'process raft request' (duration: 216.152411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:45.961835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.703773ms","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-09-10T11:02:45.961884Z","caller":"traceutil/trace.go:171","msg":"trace[1518967520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2001; }","duration":"139.805365ms","start":"2026-09-10T11:02:45.822065Z","end":"2026-09-10T11:02:45.96187Z","steps":["trace[1518967520] 'agreement among raft nodes before linearized reading' (duration: 139.632202ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:58.714924Z","caller":"traceutil/trace.go:171","msg":"trace[661372899] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"117.466693ms","start":"2026-09-10T11:02:58.597425Z","end":"2026-09-10T11:02:58.714892Z","steps":["trace[661372899] 'process raft request' (duration: 117.151048ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:04.060157Z","caller":"traceutil/trace.go:171","msg":"trace[84157047] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"145.18484ms","start":"2026-09-10T11:03:03.914939Z","end":"2026-09-10T11:03:04.060124Z","steps":["trace[84157047] 'process raft request' (duration: 144.864495ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:04.188362Z","caller":"traceutil/trace.go:171","msg":"trace[1759023308] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2192; }","duration":"126.639394ms","start":"2026-09-10T11:03:04.061695Z","end":"2026-09-10T11:03:04.188334Z","steps":["trace[1759023308] 'read index received' (duration: 111.213979ms)","trace[1759023308] 'applied index is now lower than readState.Index' (duration: 15.424185ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:03:04.188428Z","caller":"traceutil/trace.go:171","msg":"trace[1829578532] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"145.893377ms","start":"2026-09-10T11:03:04.042501Z","end":"2026-09-10T11:03:04.188394Z","steps":["trace[1829578532] 'process raft request' (duration: 130.429061ms)","trace[1829578532] 'compare' (duration: 15.246342ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:03:04.188567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.852057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:03:04.188621Z","caller":"traceutil/trace.go:171","msg":"trace[1385085196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2110; }","duration":"126.942719ms","start":"2026-09-10T11:03:04.061663Z","end":"2026-09-10T11:03:04.188606Z","steps":["trace[1385085196] 'agreement among raft nodes before linearized reading' (duration: 126.835277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:04.261005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.177244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:03:04.261074Z","caller":"traceutil/trace.go:171","msg":"trace[1031909293] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2111; }","duration":"164.299676ms","start":"2026-09-10T11:03:04.096759Z","end":"2026-09-10T11:03:04.261059Z","steps":["trace[1031909293] 'agreement among raft nodes before linearized reading' (duration: 164.156474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:04.261057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.825397ms","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-09-10T11:03:04.261141Z","caller":"traceutil/trace.go:171","msg":"trace[1764735637] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2111; }","duration":"176.955919ms","start":"2026-09-10T11:03:04.084165Z","end":"2026-09-10T11:03:04.261121Z","steps":["trace[1764735637] 'agreement among raft nodes before linearized reading' (duration: 176.704795ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:04.946013Z","caller":"traceutil/trace.go:171","msg":"trace[35602371] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2196; }","duration":"471.29666ms","start":"2026-09-10T11:03:04.474684Z","end":"2026-09-10T11:03:04.94598Z","steps":["trace[35602371] 'read index received' (duration: 471.146387ms)","trace[35602371] 'applied index is now lower than readState.Index' (duration: 148.993µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:03:04.946131Z","caller":"traceutil/trace.go:171","msg":"trace[523564187] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"488.676794ms","start":"2026-09-10T11:03:04.457399Z","end":"2026-09-10T11:03:04.946076Z","steps":["trace[523564187] 'process raft request' (duration: 488.357919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:04.946331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:04.457377Z","time spent":"488.841837ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:03:04.946432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.719136ms","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-09-10T11:03:04.946538Z","caller":"traceutil/trace.go:171","msg":"trace[1566700446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2114; }","duration":"471.882749ms","start":"2026-09-10T11:03:04.474631Z","end":"2026-09-10T11:03:04.946514Z","steps":["trace[1566700446] 'agreement among raft nodes before linearized reading' (duration: 471.513453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:04.946602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:04.474611Z","time spent":"471.977ms","remote":"127.0.0.1:58368","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-09-10T11:03:05.446822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":188483278170261803,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-10T11:03:05.566423Z","caller":"traceutil/trace.go:171","msg":"trace[1958185892] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"834.274324ms","start":"2026-09-10T11:03:04.732107Z","end":"2026-09-10T11:03:05.566381Z","steps":["trace[1958185892] 'process raft request' (duration: 833.885398ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:05.566561Z","caller":"traceutil/trace.go:171","msg":"trace[760199273] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"819.278297ms","start":"2026-09-10T11:03:04.747254Z","end":"2026-09-10T11:03:05.566532Z","steps":["trace[760199273] 'process raft request' (duration: 819.063544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:05.566706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:04.747234Z","time spent":"819.396468ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:03:05.567208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:04.732078Z","time spent":"834.500927ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:03:05.678939Z","caller":"traceutil/trace.go:171","msg":"trace[1731760971] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"726.633634ms","start":"2026-09-10T11:03:04.952282Z","end":"2026-09-10T11:03:05.678916Z","steps":["trace[1731760971] 'process raft request' (duration: 649.366593ms)","trace[1731760971] 'compare' (duration: 77.139339ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:03:05.679078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:04.952243Z","time spent":"726.749786ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:03:05.679094Z","caller":"traceutil/trace.go:171","msg":"trace[1898240667] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2197; }","duration":"732.98274ms","start":"2026-09-10T11:03:04.946091Z","end":"2026-09-10T11:03:05.679074Z","steps":["trace[1898240667] 'read index received' (duration: 619.827996ms)","trace[1898240667] 'applied index is now lower than readState.Index' (duration: 113.153754ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:03:05.679382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.744822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-10T11:03:05.679407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.150913ms","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-09-10T11:03:05.679442Z","caller":"traceutil/trace.go:171","msg":"trace[1469928115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2118; }","duration":"887.237385ms","start":"2026-09-10T11:03:04.792197Z","end":"2026-09-10T11:03:05.679435Z","steps":["trace[1469928115] 'agreement among raft nodes before linearized reading' (duration: 887.126313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:05.679461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:04.79218Z","time spent":"887.275795ms","remote":"127.0.0.1:58368","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-09-10T11:03:05.679451Z","caller":"traceutil/trace.go:171","msg":"trace[54875688] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2118; }","duration":"831.818633ms","start":"2026-09-10T11:03:04.847613Z","end":"2026-09-10T11:03:05.679431Z","steps":["trace[54875688] 'agreement among raft nodes before linearized reading' (duration: 831.702731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:05.67938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.852621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-10T11:03:05.679529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182996837s","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-09-10T11:03:05.679561Z","caller":"traceutil/trace.go:171","msg":"trace[375257902] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2118; }","duration":"1.183061299s","start":"2026-09-10T11:03:04.496491Z","end":"2026-09-10T11:03:05.679553Z","steps":["trace[375257902] 'agreement among raft nodes before linearized reading' (duration: 1.182680353s)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:05.679552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:04.847555Z","time spent":"831.946635ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-09-10T11:03:05.679579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:04.496473Z","time spent":"1.183101849s","remote":"127.0.0.1:58868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-10T11:03:05.679619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.431155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-09-10T11:03:05.679632Z","caller":"traceutil/trace.go:171","msg":"trace[1076553212] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2118; }","duration":"342.104825ms","start":"2026-09-10T11:03:05.337432Z","end":"2026-09-10T11:03:05.679537Z","steps":["trace[1076553212] 'agreement among raft nodes before linearized reading' (duration: 341.77942ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:05.679728Z","caller":"traceutil/trace.go:171","msg":"trace[33078467] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"351.03059ms","start":"2026-09-10T11:03:05.328685Z","end":"2026-09-10T11:03:05.679715Z","steps":["trace[33078467] 'process raft request' (duration: 350.195657ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:05.679799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:05.337409Z","time spent":"342.331738ms","remote":"127.0.0.1:58130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-09-10T11:03:05.67982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:05.32866Z","time spent":"351.107251ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:03:05.679682Z","caller":"traceutil/trace.go:171","msg":"trace[1456776823] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2118; }","duration":"316.520747ms","start":"2026-09-10T11:03:05.363146Z","end":"2026-09-10T11:03:05.679667Z","steps":["trace[1456776823] 'agreement among raft nodes before linearized reading' (duration: 316.357925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:05.680054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:05.363131Z","time spent":"316.915923ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12406,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-10T11:03:05.67964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202130878s","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-09-10T11:03:05.680177Z","caller":"traceutil/trace.go:171","msg":"trace[1373306574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2118; }","duration":"1.202698517s","start":"2026-09-10T11:03:04.477472Z","end":"2026-09-10T11:03:05.680171Z","steps":["trace[1373306574] 'agreement among raft nodes before linearized reading' (duration: 1.202121178s)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:05.680199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:04.477456Z","time spent":"1.202736207s","remote":"127.0.0.1:58368","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-09-10T11:03:06.101658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.248442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:03:06.101756Z","caller":"traceutil/trace.go:171","msg":"trace[943969258] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2201; }","duration":"409.517111ms","start":"2026-09-10T11:03:05.692211Z","end":"2026-09-10T11:03:06.101728Z","steps":["trace[943969258] 'read index received' (duration: 16.768114ms)","trace[943969258] 'applied index is now lower than readState.Index' (duration: 392.747537ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:03:06.101836Z","caller":"traceutil/trace.go:171","msg":"trace[1465731283] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"418.269374ms","start":"2026-09-10T11:03:05.683555Z","end":"2026-09-10T11:03:06.101824Z","steps":["trace[1465731283] 'process raft request' (duration: 275.765078ms)","trace[1465731283] 'compare' (duration: 141.986518ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:03:06.101894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:05.683548Z","time spent":"418.309365ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:03:06.102106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.898397ms","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-09-10T11:03:06.102159Z","caller":"traceutil/trace.go:171","msg":"trace[1257909298] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2120; }","duration":"409.976609ms","start":"2026-09-10T11:03:05.692172Z","end":"2026-09-10T11:03:06.102148Z","steps":["trace[1257909298] 'agreement among raft nodes before linearized reading' (duration: 409.896648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:06.102187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:05.692157Z","time spent":"410.023339ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-10T11:03:06.102301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.737886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:03:06.102406Z","caller":"traceutil/trace.go:171","msg":"trace[1242603699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2120; }","duration":"124.880669ms","start":"2026-09-10T11:03:05.977507Z","end":"2026-09-10T11:03:06.102388Z","steps":["trace[1242603699] 'agreement among raft nodes before linearized reading' (duration: 124.700016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:06.528393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.604719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:03:06.52854Z","caller":"traceutil/trace.go:171","msg":"trace[163813241] linearizableReadLoop","detail":"{readStateIndex:2204; appliedIndex:2203; }","duration":"246.395894ms","start":"2026-09-10T11:03:06.282122Z","end":"2026-09-10T11:03:06.528518Z","steps":["trace[163813241] 'read index received' (duration: 112.520821ms)","trace[163813241] 'applied index is now lower than readState.Index' (duration: 133.872833ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:03:06.528736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.614718ms","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-09-10T11:03:06.528762Z","caller":"traceutil/trace.go:171","msg":"trace[188457255] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2121; }","duration":"246.679949ms","start":"2026-09-10T11:03:06.282073Z","end":"2026-09-10T11:03:06.528752Z","steps":["trace[188457255] 'agreement among raft nodes before linearized reading' (duration: 246.496466ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:06.528896Z","caller":"traceutil/trace.go:171","msg":"trace[875172242] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"336.08815ms","start":"2026-09-10T11:03:06.192794Z","end":"2026-09-10T11:03:06.528882Z","steps":["trace[875172242] 'process raft request' (duration: 201.915762ms)","trace[875172242] 'compare' (duration: 133.440107ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:03:06.528957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:06.192773Z","time spent":"336.150912ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:03:07.182738Z","caller":"traceutil/trace.go:171","msg":"trace[830796736] linearizableReadLoop","detail":"{readStateIndex:2205; appliedIndex:2204; }","duration":"494.406317ms","start":"2026-09-10T11:03:06.688305Z","end":"2026-09-10T11:03:07.182711Z","steps":["trace[830796736] 'read index received' (duration: 439.377127ms)","trace[830796736] 'applied index is now lower than readState.Index' (duration: 55.02808ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:03:07.18288Z","caller":"traceutil/trace.go:171","msg":"trace[1878304493] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"650.114411ms","start":"2026-09-10T11:03:06.532748Z","end":"2026-09-10T11:03:07.182862Z","steps":["trace[1878304493] 'process raft request' (duration: 594.912899ms)","trace[1878304493] 'compare' (duration: 54.936738ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:03:07.182995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:06.532728Z","time spent":"650.186112ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:03:07.183288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.548134ms","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-09-10T11:03:07.183863Z","caller":"traceutil/trace.go:171","msg":"trace[1237705225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2122; }","duration":"232.159063ms","start":"2026-09-10T11:03:06.951693Z","end":"2026-09-10T11:03:07.183852Z","steps":["trace[1237705225] 'agreement among raft nodes before linearized reading' (duration: 231.442142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:07.183533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.229709ms","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-09-10T11:03:07.18399Z","caller":"traceutil/trace.go:171","msg":"trace[1872096362] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2122; }","duration":"495.768028ms","start":"2026-09-10T11:03:06.688211Z","end":"2026-09-10T11:03:07.183979Z","steps":["trace[1872096362] 'agreement among raft nodes before linearized reading' (duration: 495.224879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:07.184034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:06.688195Z","time spent":"495.809229ms","remote":"127.0.0.1:58868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-10T11:03:07.183609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.45195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:03:07.184178Z","caller":"traceutil/trace.go:171","msg":"trace[909842705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2122; }","duration":"206.067769ms","start":"2026-09-10T11:03:06.978102Z","end":"2026-09-10T11:03:07.18417Z","steps":["trace[909842705] 'agreement among raft nodes before linearized reading' (duration: 205.42437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:07.915926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.02119ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-10T11:03:07.916471Z","caller":"traceutil/trace.go:171","msg":"trace[1917351537] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"462.360023ms","start":"2026-09-10T11:03:07.454085Z","end":"2026-09-10T11:03:07.916445Z","steps":["trace[1917351537] 'process raft request' (duration: 462.070088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:07.916724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:07.454067Z","time spent":"462.483924ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:03:08.053835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.316859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:03:08.054036Z","caller":"traceutil/trace.go:171","msg":"trace[1066615395] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"481.720092ms","start":"2026-09-10T11:03:07.572295Z","end":"2026-09-10T11:03:08.054015Z","steps":["trace[1066615395] 'process raft request' (duration: 481.651861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:08.054125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:07.572221Z","time spent":"481.859904ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:03:08.054166Z","caller":"traceutil/trace.go:171","msg":"trace[1375781056] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"481.802874ms","start":"2026-09-10T11:03:07.572261Z","end":"2026-09-10T11:03:08.054064Z","steps":["trace[1375781056] 'process raft request' (duration: 344.17055ms)","trace[1375781056] 'compare' (duration: 137.120186ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:03:08.054625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:07.572206Z","time spent":"482.18032ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:03:08.515907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.138181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:03:08.516083Z","caller":"traceutil/trace.go:171","msg":"trace[1777421247] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"836.983473ms","start":"2026-09-10T11:03:07.679082Z","end":"2026-09-10T11:03:08.516066Z","steps":["trace[1777421247] 'process raft request' (duration: 501.596588ms)","trace[1777421247] 'compare' (duration: 334.942418ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:03:08.516127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:07.67906Z","time spent":"837.047243ms","remote":"127.0.0.1:58276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8560,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:03:08.516307Z","caller":"traceutil/trace.go:171","msg":"trace[1146387896] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2206; }","duration":"832.021878ms","start":"2026-09-10T11:03:07.684267Z","end":"2026-09-10T11:03:08.516289Z","steps":["trace[1146387896] 'read index received' (duration: 115.288589ms)","trace[1146387896] 'applied index is now lower than readState.Index' (duration: 716.732269ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:03:08.516451Z","caller":"traceutil/trace.go:171","msg":"trace[1373882722] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"833.023743ms","start":"2026-09-10T11:03:07.683405Z","end":"2026-09-10T11:03:08.516429Z","steps":["trace[1373882722] 'process raft request' (duration: 832.573816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:08.516557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:07.683385Z","time spent":"833.114834ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:03:08.516632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.409183ms","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-09-10T11:03:08.516662Z","caller":"traceutil/trace.go:171","msg":"trace[791501151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2128; }","duration":"832.466305ms","start":"2026-09-10T11:03:07.684186Z","end":"2026-09-10T11:03:08.516652Z","steps":["trace[791501151] 'agreement among raft nodes before linearized reading' (duration: 832.402654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:08.516684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:07.684172Z","time spent":"832.506645ms","remote":"127.0.0.1:58368","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-09-10T11:03:08.516575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.518877ms","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-09-10T11:03:08.516771Z","caller":"traceutil/trace.go:171","msg":"trace[413347149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2128; }","duration":"803.753801ms","start":"2026-09-10T11:03:07.713008Z","end":"2026-09-10T11:03:08.516762Z","steps":["trace[413347149] 'agreement among raft nodes before linearized reading' (duration: 803.498987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:08.516807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.821708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:03:08.516834Z","caller":"traceutil/trace.go:171","msg":"trace[590402451] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2128; }","duration":"255.907559ms","start":"2026-09-10T11:03:08.260918Z","end":"2026-09-10T11:03:08.516826Z","steps":["trace[590402451] 'agreement among raft nodes before linearized reading' (duration: 255.859629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:08.516818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:07.712977Z","time spent":"803.831433ms","remote":"127.0.0.1:58368","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-09-10T11:03:08.516975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.581038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-09-10T11:03:08.517832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.285511ms","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-09-10T11:03:08.517918Z","caller":"traceutil/trace.go:171","msg":"trace[698130194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2128; }","duration":"412.418093ms","start":"2026-09-10T11:03:08.10547Z","end":"2026-09-10T11:03:08.517888Z","steps":["trace[698130194] 'agreement among raft nodes before linearized reading' (duration: 412.194009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:08.517966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:08.105454Z","time spent":"412.503945ms","remote":"127.0.0.1:58368","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-09-10T11:03:08.51857Z","caller":"traceutil/trace.go:171","msg":"trace[959384221] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2128; }","duration":"325.646059ms","start":"2026-09-10T11:03:08.191347Z","end":"2026-09-10T11:03:08.516993Z","steps":["trace[959384221] 'agreement among raft nodes before linearized reading' (duration: 325.576218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:08.518671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:08.191323Z","time spent":"327.294273ms","remote":"127.0.0.1:58868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-10T11:03:36.392698Z","caller":"traceutil/trace.go:171","msg":"trace[1086773103] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"192.455755ms","start":"2026-09-10T11:03:36.200216Z","end":"2026-09-10T11:03:36.392672Z","steps":["trace[1086773103] 'process raft request' (duration: 185.794501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:36.393011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.524484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-09-10T11:03:36.393115Z","caller":"traceutil/trace.go:171","msg":"trace[275960109] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2275; }","duration":"118.672036ms","start":"2026-09-10T11:03:36.274419Z","end":"2026-09-10T11:03:36.393091Z","steps":["trace[275960109] 'agreement among raft nodes before linearized reading' (duration: 118.346761ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:36.393487Z","caller":"traceutil/trace.go:171","msg":"trace[280287631] linearizableReadLoop","detail":"{readStateIndex:2365; appliedIndex:2364; }","duration":"118.140359ms","start":"2026-09-10T11:03:36.274457Z","end":"2026-09-10T11:03:36.392597Z","steps":["trace[280287631] 'read index received' (duration: 111.559016ms)","trace[280287631] 'applied index is now lower than readState.Index' (duration: 6.580133ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:04:15.114491Z","caller":"traceutil/trace.go:171","msg":"trace[1278024415] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"108.535447ms","start":"2026-09-10T11:04:15.005793Z","end":"2026-09-10T11:04:15.114329Z","steps":["trace[1278024415] 'process raft request' (duration: 28.189708ms)","trace[1278024415] 'compare' (duration: 80.172322ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:04:52.598009Z","caller":"traceutil/trace.go:171","msg":"trace[353793197] linearizableReadLoop","detail":"{readStateIndex:2883; appliedIndex:2882; }","duration":"487.219695ms","start":"2026-09-10T11:04:52.11077Z","end":"2026-09-10T11:04:52.597989Z","steps":["trace[353793197] 'read index received' (duration: 487.143922ms)","trace[353793197] 'applied index is now lower than readState.Index' (duration: 74.603µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:04:52.598106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.047411Z","time spent":"550.691905ms","remote":"127.0.0.1:58112","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-10T11:04:52.598339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.553486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:04:52.598375Z","caller":"traceutil/trace.go:171","msg":"trace[114966322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2775; }","duration":"487.618579ms","start":"2026-09-10T11:04:52.110744Z","end":"2026-09-10T11:04:52.598362Z","steps":["trace[114966322] 'agreement among raft nodes before linearized reading' (duration: 487.549006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:52.598408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.110708Z","time spent":"487.692641ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-10T11:04:52.606323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.22427ms","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-09-10T11:04:52.606426Z","caller":"traceutil/trace.go:171","msg":"trace[1420655658] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2776; }","duration":"403.360044ms","start":"2026-09-10T11:04:52.203023Z","end":"2026-09-10T11:04:52.606383Z","steps":["trace[1420655658] 'agreement among raft nodes before linearized reading' (duration: 403.185878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:52.606482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.203008Z","time spent":"403.461817ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-09-10T11:05:24.856301Z","caller":"traceutil/trace.go:171","msg":"trace[111228522] transaction","detail":"{read_only:false; response_revision:2954; number_of_response:1; }","duration":"136.151227ms","start":"2026-09-10T11:05:24.720115Z","end":"2026-09-10T11:05:24.856267Z","steps":["trace[111228522] 'process raft request' (duration: 135.959491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:05:25.121036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.351124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:05:25.121272Z","caller":"traceutil/trace.go:171","msg":"trace[1932214696] linearizableReadLoop","detail":"{readStateIndex:3070; appliedIndex:3068; }","duration":"140.681629ms","start":"2026-09-10T11:05:24.98057Z","end":"2026-09-10T11:05:25.121251Z","steps":["trace[1932214696] 'read index received' (duration: 80.350045ms)","trace[1932214696] 'applied index is now lower than readState.Index' (duration: 60.330464ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:05:25.122091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.510692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:05:25.122143Z","caller":"traceutil/trace.go:171","msg":"trace[1940097675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2956; }","duration":"141.599475ms","start":"2026-09-10T11:05:24.980528Z","end":"2026-09-10T11:05:25.122127Z","steps":["trace[1940097675] 'agreement among raft nodes before linearized reading' (duration: 141.487622ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:05:25.122484Z","caller":"traceutil/trace.go:171","msg":"trace[1785671633] transaction","detail":"{read_only:false; response_revision:2956; number_of_response:1; }","duration":"241.453742ms","start":"2026-09-10T11:05:24.880987Z","end":"2026-09-10T11:05:25.122441Z","steps":["trace[1785671633] 'process raft request' (duration: 240.195596ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:05:25.122617Z","caller":"traceutil/trace.go:171","msg":"trace[646133765] transaction","detail":"{read_only:false; response_revision:2955; number_of_response:1; }","duration":"295.252209ms","start":"2026-09-10T11:05:24.827344Z","end":"2026-09-10T11:05:25.122596Z","steps":["trace[646133765] 'process raft request' (duration: 81.246168ms)","trace[646133765] 'compare' (duration: 211.940221ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:09:36.054292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.240066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:09:36.054493Z","caller":"traceutil/trace.go:171","msg":"trace[162900509] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"159.687814ms","start":"2026-09-10T11:09:35.894779Z","end":"2026-09-10T11:09:36.054467Z","steps":["trace[162900509] 'process raft request' (duration: 31.197114ms)","trace[162900509] 'compare' (duration: 128.087523ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:09:37.167688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.386673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:09:37.167762Z","caller":"traceutil/trace.go:171","msg":"trace[524910374] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:4975; }","duration":"120.508276ms","start":"2026-09-10T11:09:37.047236Z","end":"2026-09-10T11:09:37.167744Z","steps":["trace[524910374] 'count revisions from in-memory index tree' (duration: 120.305592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:37.168027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.193223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:09:37.168049Z","caller":"traceutil/trace.go:171","msg":"trace[198651012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4975; }","duration":"189.260274ms","start":"2026-09-10T11:09:36.978781Z","end":"2026-09-10T11:09:37.168041Z","steps":["trace[198651012] 'range keys from in-memory index tree' (duration: 189.143332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:37.168258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.269699ms","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-09-10T11:09:37.168279Z","caller":"traceutil/trace.go:171","msg":"trace[1743130627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4975; }","duration":"165.31653ms","start":"2026-09-10T11:09:37.002956Z","end":"2026-09-10T11:09:37.168272Z","steps":["trace[1743130627] 'range keys from in-memory index tree' (duration: 165.160757ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:42.840926Z","caller":"traceutil/trace.go:171","msg":"trace[1068475111] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"116.350315ms","start":"2026-09-10T11:09:42.724528Z","end":"2026-09-10T11:09:42.840878Z","steps":["trace[1068475111] 'process raft request' (duration: 116.027459ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:43.047658Z","caller":"traceutil/trace.go:171","msg":"trace[1070073772] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"199.496561ms","start":"2026-09-10T11:09:42.848137Z","end":"2026-09-10T11:09:43.047633Z","steps":["trace[1070073772] 'process raft request' (duration: 199.361098ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:43.080202Z","caller":"traceutil/trace.go:171","msg":"trace[1681977479] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"229.454098ms","start":"2026-09-10T11:09:42.850719Z","end":"2026-09-10T11:09:43.080173Z","steps":["trace[1681977479] 'process raft request' (duration: 229.095903ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:43.080276Z","caller":"traceutil/trace.go:171","msg":"trace[1234509517] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"225.925056ms","start":"2026-09-10T11:09:42.854284Z","end":"2026-09-10T11:09:43.080209Z","steps":["trace[1234509517] 'process raft request' (duration: 225.784903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:43.08044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.577124ms","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-09-10T11:09:43.0802Z","caller":"traceutil/trace.go:171","msg":"trace[2100479213] linearizableReadLoop","detail":"{readStateIndex:5221; appliedIndex:5218; }","duration":"138.32457ms","start":"2026-09-10T11:09:42.94184Z","end":"2026-09-10T11:09:43.080164Z","steps":["trace[2100479213] 'read index received' (duration: 105.674485ms)","trace[2100479213] 'applied index is now lower than readState.Index' (duration: 32.648985ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:09:43.080535Z","caller":"traceutil/trace.go:171","msg":"trace[1770576608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5026; }","duration":"138.703267ms","start":"2026-09-10T11:09:42.941785Z","end":"2026-09-10T11:09:43.080488Z","steps":["trace[1770576608] 'agreement among raft nodes before linearized reading' (duration: 138.483773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:43.080648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.052829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:09:43.080685Z","caller":"traceutil/trace.go:171","msg":"trace[896896185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5026; }","duration":"102.12711ms","start":"2026-09-10T11:09:42.978548Z","end":"2026-09-10T11:09:43.080675Z","steps":["trace[896896185] 'agreement among raft nodes before linearized reading' (duration: 102.068109ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:44.614054Z","caller":"traceutil/trace.go:171","msg":"trace[425111904] linearizableReadLoop","detail":"{readStateIndex:5263; appliedIndex:5262; }","duration":"124.499235ms","start":"2026-09-10T11:09:44.489529Z","end":"2026-09-10T11:09:44.614028Z","steps":["trace[425111904] 'read index received' (duration: 101.153573ms)","trace[425111904] 'applied index is now lower than readState.Index' (duration: 23.344502ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:09:44.614166Z","caller":"traceutil/trace.go:171","msg":"trace[1693259624] transaction","detail":"{read_only:false; response_revision:5068; number_of_response:1; }","duration":"177.868774ms","start":"2026-09-10T11:09:44.436284Z","end":"2026-09-10T11:09:44.614153Z","steps":["trace[1693259624] 'process raft request' (duration: 154.378811ms)","trace[1693259624] 'compare' (duration: 23.148278ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:09:44.614393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.512144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-10T11:09:44.61446Z","caller":"traceutil/trace.go:171","msg":"trace[1879650293] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5068; }","duration":"124.595425ms","start":"2026-09-10T11:09:44.489848Z","end":"2026-09-10T11:09:44.614444Z","steps":["trace[1879650293] 'agreement among raft nodes before linearized reading' (duration: 124.370501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:44.615021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.471701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:72 size:60103"} {"level":"info","ts":"2026-09-10T11:09:44.615078Z","caller":"traceutil/trace.go:171","msg":"trace[1177269096] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:72; response_revision:5068; }","duration":"125.549832ms","start":"2026-09-10T11:09:44.489516Z","end":"2026-09-10T11:09:44.615066Z","steps":["trace[1177269096] 'agreement among raft nodes before linearized reading' (duration: 124.919651ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:47.211523Z","caller":"traceutil/trace.go:171","msg":"trace[1529053329] linearizableReadLoop","detail":"{readStateIndex:5319; appliedIndex:5318; }","duration":"232.701281ms","start":"2026-09-10T11:09:46.978794Z","end":"2026-09-10T11:09:47.211495Z","steps":["trace[1529053329] 'read index received' (duration: 232.492448ms)","trace[1529053329] 'applied index is now lower than readState.Index' (duration: 207.953µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:09:47.211582Z","caller":"traceutil/trace.go:171","msg":"trace[672725808] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"279.079927ms","start":"2026-09-10T11:09:46.93247Z","end":"2026-09-10T11:09:47.21155Z","steps":["trace[672725808] 'process raft request' (duration: 278.870444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:47.211741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.907325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:09:47.211806Z","caller":"traceutil/trace.go:171","msg":"trace[1184306062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5124; }","duration":"233.012007ms","start":"2026-09-10T11:09:46.978783Z","end":"2026-09-10T11:09:47.211795Z","steps":["trace[1184306062] 'agreement among raft nodes before linearized reading' (duration: 232.852694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:47.21212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.969596ms","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-09-10T11:09:47.212199Z","caller":"traceutil/trace.go:171","msg":"trace[1037936244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5124; }","duration":"150.092889ms","start":"2026-09-10T11:09:47.06209Z","end":"2026-09-10T11:09:47.212183Z","steps":["trace[1037936244] 'agreement among raft nodes before linearized reading' (duration: 149.610641ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:52.547505Z","caller":"traceutil/trace.go:171","msg":"trace[25125594] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"110.19732ms","start":"2026-09-10T11:09:52.437276Z","end":"2026-09-10T11:09:52.547474Z","steps":["trace[25125594] 'process raft request' (duration: 110.046687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:57.936567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.326237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:09:57.936893Z","caller":"traceutil/trace.go:171","msg":"trace[170806268] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"249.280181ms","start":"2026-09-10T11:09:57.687499Z","end":"2026-09-10T11:09:57.936779Z","steps":["trace[170806268] 'process raft request' (duration: 60.642128ms)","trace[170806268] 'compare' (duration: 188.032812ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:09:57.937204Z","caller":"traceutil/trace.go:171","msg":"trace[622451291] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"249.222799ms","start":"2026-09-10T11:09:57.687958Z","end":"2026-09-10T11:09:57.937181Z","steps":["trace[622451291] 'process raft request' (duration: 248.725021ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:57.93794Z","caller":"traceutil/trace.go:171","msg":"trace[2029548498] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"245.554665ms","start":"2026-09-10T11:09:57.69236Z","end":"2026-09-10T11:09:57.937915Z","steps":["trace[2029548498] 'process raft request' (duration: 244.453306ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:57.937946Z","caller":"traceutil/trace.go:171","msg":"trace[900202665] linearizableReadLoop","detail":"{readStateIndex:5474; appliedIndex:5471; }","duration":"214.414602ms","start":"2026-09-10T11:09:57.723496Z","end":"2026-09-10T11:09:57.93791Z","steps":["trace[900202665] 'read index received' (duration: 24.548989ms)","trace[900202665] 'applied index is now lower than readState.Index' (duration: 189.862963ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:09:57.938241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.715057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:09:57.938298Z","caller":"traceutil/trace.go:171","msg":"trace[251693525] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5274; }","duration":"214.822318ms","start":"2026-09-10T11:09:57.72346Z","end":"2026-09-10T11:09:57.938282Z","steps":["trace[251693525] 'agreement among raft nodes before linearized reading' (duration: 214.539723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:58.160664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.302009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:09:58.161024Z","caller":"traceutil/trace.go:171","msg":"trace[1187196559] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"206.233127ms","start":"2026-09-10T11:09:57.954773Z","end":"2026-09-10T11:09:58.161006Z","steps":["trace[1187196559] 'process raft request' (duration: 89.516471ms)","trace[1187196559] 'compare' (duration: 116.191517ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:09:58.161278Z","caller":"traceutil/trace.go:171","msg":"trace[138197014] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"205.578965ms","start":"2026-09-10T11:09:57.95569Z","end":"2026-09-10T11:09:58.161269Z","steps":["trace[138197014] 'process raft request' (duration: 205.062477ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:58.161322Z","caller":"traceutil/trace.go:171","msg":"trace[651085616] linearizableReadLoop","detail":"{readStateIndex:5476; appliedIndex:5474; }","duration":"198.230638ms","start":"2026-09-10T11:09:57.962581Z","end":"2026-09-10T11:09:58.160811Z","steps":["trace[651085616] 'read index received' (duration: 81.722896ms)","trace[651085616] 'applied index is now lower than readState.Index' (duration: 116.506782ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:09:58.161481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.904859ms","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-09-10T11:09:58.161514Z","caller":"traceutil/trace.go:171","msg":"trace[1711140083] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5276; }","duration":"198.97478ms","start":"2026-09-10T11:09:57.96253Z","end":"2026-09-10T11:09:58.161504Z","steps":["trace[1711140083] 'agreement among raft nodes before linearized reading' (duration: 198.850818ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:58.625008Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2816} {"level":"info","ts":"2026-09-10T11:09:58.717031Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2816,"took":"90.649711ms","hash":1908646110,"current-db-size-bytes":52932608,"current-db-size":"53 MB","current-db-size-in-use-bytes":27545600,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-10T11:09:58.717083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1908646110,"revision":2816,"compact-revision":-1} {"level":"warn","ts":"2026-09-10T11:14:05.528644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.433089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107259"} {"level":"info","ts":"2026-09-10T11:14:05.528766Z","caller":"traceutil/trace.go:171","msg":"trace[1161139281] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:7060; }","duration":"108.612686ms","start":"2026-09-10T11:14:05.420127Z","end":"2026-09-10T11:14:05.52874Z","steps":["trace[1161139281] 'range keys from in-memory index tree' (duration: 108.101997ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:14:58.634538Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5280} {"level":"info","ts":"2026-09-10T11:14:58.786598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5280,"took":"150.096736ms","hash":886653174,"current-db-size-bytes":52932608,"current-db-size":"53 MB","current-db-size-in-use-bytes":18939904,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-10T11:14:58.786788Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":886653174,"revision":5280,"compact-revision":2816}