{"level":"warn","ts":"2026-08-12T17:35:28.459437Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T17:35:28.459552Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.157:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.157:2380","--initial-cluster=instance=https://199.19.213.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.157:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-12T17:35:28.459627Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T17:35:28.459643Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.157:2380"]} {"level":"info","ts":"2026-08-12T17:35:28.459669Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-12T17:35:28.460271Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"]} {"level":"info","ts":"2026-08-12T17:35:28.460395Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.157:2380"],"listen-peer-urls":["https://199.19.213.157:2380"],"advertise-client-urls":["https://199.19.213.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.157:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-12T17:35:28.465201Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.566377ms"} {"level":"info","ts":"2026-08-12T17:35:28.474187Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"10058a425931b8de","cluster-id":"2ff97ff1d0272754"} {"level":"info","ts":"2026-08-12T17:35:28.474279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T17:35:28.474303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became follower at term 0"} {"level":"info","ts":"2026-08-12T17:35:28.474321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 10058a425931b8de [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T17:35:28.474332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became follower at term 1"} {"level":"info","ts":"2026-08-12T17:35:28.47438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=(1154480897059305694)"} {"level":"warn","ts":"2026-08-12T17:35:28.48259Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T17:35:28.484064Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T17:35:28.485374Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-12T17:35:28.488994Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"10058a425931b8de","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T17:35:28.489962Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"10058a425931b8de","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T17:35:28.490221Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-12T17:35:28.491005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=(1154480897059305694)"} {"level":"info","ts":"2026-08-12T17:35:28.491059Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-12T17:35:28.491107Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-12T17:35:28.49134Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ff97ff1d0272754","local-member-id":"10058a425931b8de","added-peer-id":"10058a425931b8de","added-peer-peer-urls":["https://199.19.213.157:2380"]} {"level":"info","ts":"2026-08-12T17:35:28.493172Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-12T17:35:28.493271Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.157:2380"} {"level":"info","ts":"2026-08-12T17:35:28.493363Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.157:2380"} {"level":"info","ts":"2026-08-12T17:35:28.493398Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"10058a425931b8de","initial-advertise-peer-urls":["https://199.19.213.157:2380"],"listen-peer-urls":["https://199.19.213.157:2380"],"advertise-client-urls":["https://199.19.213.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T17:35:28.493435Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T17:35:28.574718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T17:35:28.574825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T17:35:28.575122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de received MsgPreVoteResp from 10058a425931b8de at term 1"} {"level":"info","ts":"2026-08-12T17:35:28.575314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became candidate at term 2"} {"level":"info","ts":"2026-08-12T17:35:28.575341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de received MsgVoteResp from 10058a425931b8de at term 2"} {"level":"info","ts":"2026-08-12T17:35:28.575547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became leader at term 2"} {"level":"info","ts":"2026-08-12T17:35:28.575669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 10058a425931b8de elected leader 10058a425931b8de at term 2"} {"level":"info","ts":"2026-08-12T17:35:28.576829Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T17:35:28.577797Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T17:35:28.577819Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T17:35:28.578468Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T17:35:28.578548Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T17:35:28.582512Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.157:2379"} {"level":"info","ts":"2026-08-12T17:35:28.582698Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T17:35:28.577776Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"10058a425931b8de","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.157:2379]}","request-path":"/0/members/10058a425931b8de/attributes","cluster-id":"2ff97ff1d0272754","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T17:35:28.592251Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ff97ff1d0272754","local-member-id":"10058a425931b8de","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T17:35:28.593889Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T17:35:28.593966Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T17:35:32.949092Z","caller":"traceutil/trace.go:171","msg":"trace[202053227] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"226.796939ms","start":"2026-08-12T17:35:32.722271Z","end":"2026-08-12T17:35:32.949068Z","steps":["trace[202053227] 'process raft request' (duration: 226.666727ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:35:33.364389Z","caller":"traceutil/trace.go:171","msg":"trace[834384122] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:210; }","duration":"536.320165ms","start":"2026-08-12T17:35:32.828046Z","end":"2026-08-12T17:35:33.364366Z","steps":["trace[834384122] 'read index received' (duration: 120.932279ms)","trace[834384122] 'applied index is now lower than readState.Index' (duration: 415.387025ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:35:33.364469Z","caller":"traceutil/trace.go:171","msg":"trace[977628043] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"442.096239ms","start":"2026-08-12T17:35:32.922345Z","end":"2026-08-12T17:35:33.364441Z","steps":["trace[977628043] 'process raft request' (duration: 441.927506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:35:33.364595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.530298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:35:33.364662Z","caller":"traceutil/trace.go:171","msg":"trace[398893825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:207; }","duration":"536.64038ms","start":"2026-08-12T17:35:32.828011Z","end":"2026-08-12T17:35:33.364651Z","steps":["trace[398893825] 'agreement among raft nodes before linearized reading' (duration: 536.509648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:35:33.364701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:35:32.827999Z","time spent":"536.690611ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T17:35:33.364685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.346567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-a9htvl\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:35:33.364764Z","caller":"traceutil/trace.go:171","msg":"trace[1178229858] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-a9htvl; range_end:; response_count:0; response_revision:207; }","duration":"411.46384ms","start":"2026-08-12T17:35:32.953276Z","end":"2026-08-12T17:35:33.364739Z","steps":["trace[1178229858] 'agreement among raft nodes before linearized reading' (duration: 411.328987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:35:33.364814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:35:32.953259Z","time spent":"411.547861ms","remote":"127.0.0.1:32954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-a9htvl\" "} {"level":"warn","ts":"2026-08-12T17:35:33.364372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:35:32.812998Z","time spent":"551.364214ms","remote":"127.0.0.1:32926","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-12T17:35:33.364984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:35:32.922315Z","time spent":"442.198001ms","remote":"127.0.0.1:33042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T17:35:33.365038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.866492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:35:33.365076Z","caller":"traceutil/trace.go:171","msg":"trace[1299313489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:207; }","duration":"160.956702ms","start":"2026-08-12T17:35:33.20411Z","end":"2026-08-12T17:35:33.365067Z","steps":["trace[1299313489] 'agreement among raft nodes before linearized reading' (duration: 160.896121ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:35:33.612156Z","caller":"traceutil/trace.go:171","msg":"trace[1221663440] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"245.032033ms","start":"2026-08-12T17:35:33.367087Z","end":"2026-08-12T17:35:33.612119Z","steps":["trace[1221663440] 'process raft request' (duration: 203.592918ms)","trace[1221663440] 'compare' (duration: 41.240182ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:35:33.612653Z","caller":"traceutil/trace.go:171","msg":"trace[1882434643] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"243.230653ms","start":"2026-08-12T17:35:33.369387Z","end":"2026-08-12T17:35:33.612618Z","steps":["trace[1882434643] 'process raft request' (duration: 243.178853ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:35:33.612833Z","caller":"traceutil/trace.go:171","msg":"trace[1865562818] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"243.541079ms","start":"2026-08-12T17:35:33.369268Z","end":"2026-08-12T17:35:33.612809Z","steps":["trace[1865562818] 'process raft request' (duration: 243.226143ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:35:33.612838Z","caller":"traceutil/trace.go:171","msg":"trace[775031272] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"244.20888ms","start":"2026-08-12T17:35:33.368603Z","end":"2026-08-12T17:35:33.612812Z","steps":["trace[775031272] 'process raft request' (duration: 243.665851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:03.743884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.501653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:36:03.744106Z","caller":"traceutil/trace.go:171","msg":"trace[1290337678] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"158.603307ms","start":"2026-08-12T17:36:03.585461Z","end":"2026-08-12T17:36:03.744064Z","steps":["trace[1290337678] 'process raft request' (duration: 36.692323ms)","trace[1290337678] 'compare' (duration: 121.326335ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:36:05.69242Z","caller":"traceutil/trace.go:171","msg":"trace[342580461] transaction","detail":"{read_only:false; number_of_response:1; response_revision:531; }","duration":"297.411979ms","start":"2026-08-12T17:36:05.394951Z","end":"2026-08-12T17:36:05.692363Z","steps":["trace[342580461] 'process raft request' (duration: 297.139186ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:05.692698Z","caller":"traceutil/trace.go:171","msg":"trace[1400775796] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"297.6342ms","start":"2026-08-12T17:36:05.395036Z","end":"2026-08-12T17:36:05.69267Z","steps":["trace[1400775796] 'process raft request' (duration: 297.130426ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:05.69298Z","caller":"traceutil/trace.go:171","msg":"trace[1664694143] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"298.125154ms","start":"2026-08-12T17:36:05.39484Z","end":"2026-08-12T17:36:05.692965Z","steps":["trace[1664694143] 'process raft request' (duration: 297.154107ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:05.692982Z","caller":"traceutil/trace.go:171","msg":"trace[192352566] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"290.157977ms","start":"2026-08-12T17:36:05.402761Z","end":"2026-08-12T17:36:05.692919Z","steps":["trace[192352566] 'process raft request' (duration: 289.921166ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:05.692983Z","caller":"traceutil/trace.go:171","msg":"trace[1387761368] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"289.199671ms","start":"2026-08-12T17:36:05.403759Z","end":"2026-08-12T17:36:05.692959Z","steps":["trace[1387761368] 'process raft request' (duration: 289.109387ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:05.693027Z","caller":"traceutil/trace.go:171","msg":"trace[784923448] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"290.090354ms","start":"2026-08-12T17:36:05.402883Z","end":"2026-08-12T17:36:05.692973Z","steps":["trace[784923448] 'process raft request' (duration: 289.887704ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:05.693058Z","caller":"traceutil/trace.go:171","msg":"trace[42957852] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"289.945578ms","start":"2026-08-12T17:36:05.403101Z","end":"2026-08-12T17:36:05.693046Z","steps":["trace[42957852] 'process raft request' (duration: 289.707256ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:05.693002Z","caller":"traceutil/trace.go:171","msg":"trace[284479488] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"291.03906ms","start":"2026-08-12T17:36:05.401929Z","end":"2026-08-12T17:36:05.692968Z","steps":["trace[284479488] 'process raft request' (duration: 290.366477ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:05.693439Z","caller":"traceutil/trace.go:171","msg":"trace[1687050480] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"290.120256ms","start":"2026-08-12T17:36:05.402822Z","end":"2026-08-12T17:36:05.692942Z","steps":["trace[1687050480] 'process raft request' (duration: 289.912335ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:05.709659Z","caller":"traceutil/trace.go:171","msg":"trace[2090986408] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"302.132038ms","start":"2026-08-12T17:36:05.407497Z","end":"2026-08-12T17:36:05.709629Z","steps":["trace[2090986408] 'process raft request' (duration: 301.384232ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:05.709775Z","caller":"traceutil/trace.go:171","msg":"trace[1403510218] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"158.118345ms","start":"2026-08-12T17:36:05.55164Z","end":"2026-08-12T17:36:05.709759Z","steps":["trace[1403510218] 'process raft request' (duration: 158.021211ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:05.709789Z","caller":"traceutil/trace.go:171","msg":"trace[55099063] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"277.42636ms","start":"2026-08-12T17:36:05.43234Z","end":"2026-08-12T17:36:05.709766Z","steps":["trace[55099063] 'process raft request' (duration: 276.748867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:05.714918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:36:05.407478Z","time spent":"302.243273ms","remote":"127.0.0.1:32898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:36:05.715038Z","caller":"traceutil/trace.go:171","msg":"trace[702732646] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"250.709985ms","start":"2026-08-12T17:36:05.459201Z","end":"2026-08-12T17:36:05.709911Z","steps":["trace[702732646] 'process raft request' (duration: 250.006731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:09.382384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.017321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:36:09.382479Z","caller":"traceutil/trace.go:171","msg":"trace[817238036] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"125.283372ms","start":"2026-08-12T17:36:09.257182Z","end":"2026-08-12T17:36:09.382465Z","steps":["trace[817238036] 'read index received' (duration: 29.631µs)","trace[817238036] 'applied index is now lower than readState.Index' (duration: 125.252961ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:36:09.382521Z","caller":"traceutil/trace.go:171","msg":"trace[1240861683] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"279.870693ms","start":"2026-08-12T17:36:09.102611Z","end":"2026-08-12T17:36:09.382481Z","steps":["trace[1240861683] 'process raft request' (duration: 57.699845ms)","trace[1240861683] 'compare' (duration: 221.900725ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:36:09.382726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.299953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:36:09.382801Z","caller":"traceutil/trace.go:171","msg":"trace[797758774] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:615; }","duration":"125.409338ms","start":"2026-08-12T17:36:09.257372Z","end":"2026-08-12T17:36:09.382781Z","steps":["trace[797758774] 'agreement among raft nodes before linearized reading' (duration: 125.212829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:09.3829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.559694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:36:09.382962Z","caller":"traceutil/trace.go:171","msg":"trace[1672129404] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:615; }","duration":"104.652659ms","start":"2026-08-12T17:36:09.278297Z","end":"2026-08-12T17:36:09.38295Z","steps":["trace[1672129404] 'agreement among raft nodes before linearized reading' (duration: 104.528733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:09.383018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.004975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:36:09.383058Z","caller":"traceutil/trace.go:171","msg":"trace[1188974388] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:615; }","duration":"105.057277ms","start":"2026-08-12T17:36:09.277989Z","end":"2026-08-12T17:36:09.383046Z","steps":["trace[1188974388] 'agreement among raft nodes before linearized reading' (duration: 104.997235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:09.38313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.350881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:36:09.383152Z","caller":"traceutil/trace.go:171","msg":"trace[297759441] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:615; }","duration":"105.374302ms","start":"2026-08-12T17:36:09.277772Z","end":"2026-08-12T17:36:09.383146Z","steps":["trace[297759441] 'agreement among raft nodes before linearized reading' (duration: 105.34033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:09.383262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.132185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:36:09.38329Z","caller":"traceutil/trace.go:171","msg":"trace[767663657] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:615; }","duration":"125.170757ms","start":"2026-08-12T17:36:09.258111Z","end":"2026-08-12T17:36:09.383282Z","steps":["trace[767663657] 'agreement among raft nodes before linearized reading' (duration: 125.132836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:09.38361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.414105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:36:09.383653Z","caller":"traceutil/trace.go:171","msg":"trace[431095795] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:615; }","duration":"126.483088ms","start":"2026-08-12T17:36:09.257155Z","end":"2026-08-12T17:36:09.383638Z","steps":["trace[431095795] 'agreement among raft nodes before linearized reading' (duration: 125.409168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:09.844278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.204805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:36:09.844368Z","caller":"traceutil/trace.go:171","msg":"trace[558616316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"132.345061ms","start":"2026-08-12T17:36:09.712004Z","end":"2026-08-12T17:36:09.844349Z","steps":["trace[558616316] 'range keys from in-memory index tree' (duration: 132.10152ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:13.493844Z","caller":"traceutil/trace.go:171","msg":"trace[95997561] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"784.929778ms","start":"2026-08-12T17:36:12.708895Z","end":"2026-08-12T17:36:13.493825Z","steps":["trace[95997561] 'process raft request' (duration: 784.756761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:13.494131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:36:12.70887Z","time spent":"785.049494ms","remote":"127.0.0.1:33042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:36:14.439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260631200685954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T17:36:14.940158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260631200685954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T17:36:15.253355Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.345741587s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T17:36:15.253617Z","caller":"traceutil/trace.go:171","msg":"trace[2090478940] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"1.315327356s","start":"2026-08-12T17:36:13.938274Z","end":"2026-08-12T17:36:15.253601Z","steps":["trace[2090478940] 'read index received' (duration: 1.31520537s)","trace[2090478940] 'applied index is now lower than readState.Index' (duration: 121.166µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:36:15.253681Z","caller":"traceutil/trace.go:171","msg":"trace[1821700145] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.346167535s","start":"2026-08-12T17:36:13.907489Z","end":"2026-08-12T17:36:15.253657Z","steps":["trace[1821700145] 'process raft request' (duration: 1.345998298s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:15.253774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315483994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T17:36:15.253799Z","caller":"traceutil/trace.go:171","msg":"trace[2124426305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"1.315532465s","start":"2026-08-12T17:36:13.938259Z","end":"2026-08-12T17:36:15.253792Z","steps":["trace[2124426305] 'agreement among raft nodes before linearized reading' (duration: 1.315433051s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:15.253818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:36:13.938251Z","time spent":"1.315562346s","remote":"127.0.0.1:43972","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-08-12T17:36:15.253808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:36:13.907465Z","time spent":"1.346265629s","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:36:15.254014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.651349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-12T17:36:15.254081Z","caller":"traceutil/trace.go:171","msg":"trace[1252566331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"847.759283ms","start":"2026-08-12T17:36:14.406302Z","end":"2026-08-12T17:36:15.254062Z","steps":["trace[1252566331] 'agreement among raft nodes before linearized reading' (duration: 847.506462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:15.254125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:36:14.40628Z","time spent":"847.834956ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T17:36:15.254637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272610241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-12T17:36:15.25491Z","caller":"traceutil/trace.go:171","msg":"trace[618744149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"1.27279621s","start":"2026-08-12T17:36:13.981955Z","end":"2026-08-12T17:36:15.254751Z","steps":["trace[618744149] 'agreement among raft nodes before linearized reading' (duration: 1.271849568s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:15.254988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:36:13.981935Z","time spent":"1.27303724s","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T17:36:17.262161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.262611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:36:17.262266Z","caller":"traceutil/trace.go:171","msg":"trace[669966883] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"403.24698ms","start":"2026-08-12T17:36:16.859002Z","end":"2026-08-12T17:36:17.262249Z","steps":["trace[669966883] 'read index received' (duration: 82.781331ms)","trace[669966883] 'applied index is now lower than readState.Index' (duration: 320.464179ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:36:17.262278Z","caller":"traceutil/trace.go:171","msg":"trace[1265987184] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"407.047996ms","start":"2026-08-12T17:36:16.855204Z","end":"2026-08-12T17:36:17.262252Z","steps":["trace[1265987184] 'process raft request' (duration: 86.555095ms)","trace[1265987184] 'compare' (duration: 320.129975ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:36:17.262371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.356965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T17:36:17.2624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:36:16.855195Z","time spent":"407.144891ms","remote":"127.0.0.1:32898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:36:17.26247Z","caller":"traceutil/trace.go:171","msg":"trace[630974006] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:664; }","duration":"403.410847ms","start":"2026-08-12T17:36:16.858986Z","end":"2026-08-12T17:36:17.262397Z","steps":["trace[630974006] 'agreement among raft nodes before linearized reading' (duration: 403.327594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:17.262609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:36:16.858978Z","time spent":"403.593425ms","remote":"127.0.0.1:32898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-08-12T17:36:17.265085Z","caller":"traceutil/trace.go:171","msg":"trace[1115926965] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"402.233515ms","start":"2026-08-12T17:36:16.862835Z","end":"2026-08-12T17:36:17.265069Z","steps":["trace[1115926965] 'process raft request' (duration: 402.141901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:17.265227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:36:16.862825Z","time spent":"402.310068ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:36:17.281832Z","caller":"traceutil/trace.go:171","msg":"trace[63870656] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"229.239959ms","start":"2026-08-12T17:36:17.052565Z","end":"2026-08-12T17:36:17.281805Z","steps":["trace[63870656] 'process raft request' (duration: 229.101433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:27.660394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.545359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-12T17:36:27.660413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.117372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T17:36:27.660487Z","caller":"traceutil/trace.go:171","msg":"trace[974087364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:786; }","duration":"167.695576ms","start":"2026-08-12T17:36:27.49277Z","end":"2026-08-12T17:36:27.660465Z","steps":["trace[974087364] 'range keys from in-memory index tree' (duration: 167.355312ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:27.660499Z","caller":"traceutil/trace.go:171","msg":"trace[626183199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"167.257818ms","start":"2026-08-12T17:36:27.49322Z","end":"2026-08-12T17:36:27.660478Z","steps":["trace[626183199] 'range keys from in-memory index tree' (duration: 166.916494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:31.014044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.885193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:36:31.014109Z","caller":"traceutil/trace.go:171","msg":"trace[1330894362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"302.008149ms","start":"2026-08-12T17:36:30.712087Z","end":"2026-08-12T17:36:31.014095Z","steps":["trace[1330894362] 'range keys from in-memory index tree' (duration: 301.832862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:31.014139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:36:30.712071Z","time spent":"302.05988ms","remote":"127.0.0.1:32836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T17:36:31.296219Z","caller":"traceutil/trace.go:171","msg":"trace[430514887] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"280.708393ms","start":"2026-08-12T17:36:31.015492Z","end":"2026-08-12T17:36:31.296201Z","steps":["trace[430514887] 'read index received' (duration: 280.561818ms)","trace[430514887] 'applied index is now lower than readState.Index' (duration: 145.555µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:36:31.296313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.802197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:36:31.296365Z","caller":"traceutil/trace.go:171","msg":"trace[838717478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"280.869269ms","start":"2026-08-12T17:36:31.01546Z","end":"2026-08-12T17:36:31.296329Z","steps":["trace[838717478] 'agreement among raft nodes before linearized reading' (duration: 280.813867ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:36:31.297287Z","caller":"traceutil/trace.go:171","msg":"trace[1915514983] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"473.096581ms","start":"2026-08-12T17:36:30.823168Z","end":"2026-08-12T17:36:31.296264Z","steps":["trace[1915514983] 'process raft request' (duration: 472.923204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:36:31.297814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:36:30.82314Z","time spent":"474.574668ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:37:03.212828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260631200687014,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T17:37:03.35248Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.022260838s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T17:37:03.352712Z","caller":"traceutil/trace.go:171","msg":"trace[2118390457] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"640.925479ms","start":"2026-08-12T17:37:02.711773Z","end":"2026-08-12T17:37:03.352698Z","steps":["trace[2118390457] 'read index received' (duration: 640.794185ms)","trace[2118390457] 'applied index is now lower than readState.Index' (duration: 130.374µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:37:03.352792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.008743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:37:03.352796Z","caller":"traceutil/trace.go:171","msg":"trace[1398154163] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"1.02265365s","start":"2026-08-12T17:37:02.330123Z","end":"2026-08-12T17:37:03.352777Z","steps":["trace[1398154163] 'process raft request' (duration: 1.022474594s)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:37:03.352831Z","caller":"traceutil/trace.go:171","msg":"trace[282021273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"641.080785ms","start":"2026-08-12T17:37:02.711731Z","end":"2026-08-12T17:37:03.352812Z","steps":["trace[282021273] 'agreement among raft nodes before linearized reading' (duration: 641.010363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:03.352857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:02.711711Z","time spent":"641.139276ms","remote":"127.0.0.1:32836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T17:37:03.352912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:02.330099Z","time spent":"1.022751013s","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:37:20.818795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.996797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:37:20.818972Z","caller":"traceutil/trace.go:171","msg":"trace[1251442999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"107.197073ms","start":"2026-08-12T17:37:20.711733Z","end":"2026-08-12T17:37:20.81893Z","steps":["trace[1251442999] 'range keys from in-memory index tree' (duration: 106.921575ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:37:27.403632Z","caller":"traceutil/trace.go:171","msg":"trace[739020906] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"102.144025ms","start":"2026-08-12T17:37:27.301459Z","end":"2026-08-12T17:37:27.403603Z","steps":["trace[739020906] 'process raft request' (duration: 101.96574ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:37:39.123659Z","caller":"traceutil/trace.go:171","msg":"trace[198560196] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"100.318797ms","start":"2026-08-12T17:37:39.023317Z","end":"2026-08-12T17:37:39.123636Z","steps":["trace[198560196] 'process raft request' (duration: 99.909536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:40.317671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.445315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:37:40.317829Z","caller":"traceutil/trace.go:171","msg":"trace[276386404] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:1731; }","duration":"111.621361ms","start":"2026-08-12T17:37:40.206195Z","end":"2026-08-12T17:37:40.317816Z","steps":["trace[276386404] 'range keys from in-memory index tree' (duration: 111.421495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:40.31778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.61636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:37:40.317961Z","caller":"traceutil/trace.go:171","msg":"trace[122994844] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:1731; }","duration":"111.800225ms","start":"2026-08-12T17:37:40.206144Z","end":"2026-08-12T17:37:40.317944Z","steps":["trace[122994844] 'count revisions from in-memory index tree' (duration: 111.588599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:40.317771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.100907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:37:40.318057Z","caller":"traceutil/trace.go:171","msg":"trace[1665156586] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:1731; }","duration":"111.431235ms","start":"2026-08-12T17:37:40.206622Z","end":"2026-08-12T17:37:40.318053Z","steps":["trace[1665156586] 'range keys from in-memory index tree' (duration: 111.045746ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:37:40.320301Z","caller":"traceutil/trace.go:171","msg":"trace[122152318] linearizableReadLoop","detail":"{readStateIndex:1795; appliedIndex:1794; }","duration":"101.181909ms","start":"2026-08-12T17:37:40.219099Z","end":"2026-08-12T17:37:40.320281Z","steps":["trace[122152318] 'read index received' (duration: 100.939612ms)","trace[122152318] 'applied index is now lower than readState.Index' (duration: 241.467µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:37:40.320444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.321152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:37:40.32048Z","caller":"traceutil/trace.go:171","msg":"trace[1307864750] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:1732; }","duration":"101.396045ms","start":"2026-08-12T17:37:40.219073Z","end":"2026-08-12T17:37:40.320469Z","steps":["trace[1307864750] 'agreement among raft nodes before linearized reading' (duration: 101.318953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:40.320443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.22666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:37:40.320722Z","caller":"traceutil/trace.go:171","msg":"trace[539448545] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:1732; }","duration":"101.524418ms","start":"2026-08-12T17:37:40.21919Z","end":"2026-08-12T17:37:40.320714Z","steps":["trace[539448545] 'agreement among raft nodes before linearized reading' (duration: 101.22347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:40.320871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.277742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:37:40.321775Z","caller":"traceutil/trace.go:171","msg":"trace[429189894] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:1732; }","duration":"102.191485ms","start":"2026-08-12T17:37:40.219571Z","end":"2026-08-12T17:37:40.321762Z","steps":["trace[429189894] 'agreement among raft nodes before linearized reading' (duration: 101.283182ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:37:40.321091Z","caller":"traceutil/trace.go:171","msg":"trace[604366003] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"106.199919ms","start":"2026-08-12T17:37:40.214858Z","end":"2026-08-12T17:37:40.321057Z","steps":["trace[604366003] 'process raft request' (duration: 105.251914ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:37:52.08309Z","caller":"traceutil/trace.go:171","msg":"trace[218555642] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1966; }","duration":"158.16745ms","start":"2026-08-12T17:37:51.924905Z","end":"2026-08-12T17:37:52.083072Z","steps":["trace[218555642] 'read index received' (duration: 106.990557ms)","trace[218555642] 'applied index is now lower than readState.Index' (duration: 51.175893ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:37:52.083239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.326134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-12T17:37:52.083267Z","caller":"traceutil/trace.go:171","msg":"trace[1930559529] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1897; }","duration":"158.400346ms","start":"2026-08-12T17:37:51.92486Z","end":"2026-08-12T17:37:52.08326Z","steps":["trace[1930559529] 'agreement among raft nodes before linearized reading' (duration: 158.293683ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:37:52.083178Z","caller":"traceutil/trace.go:171","msg":"trace[841104092] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"164.873367ms","start":"2026-08-12T17:37:51.918275Z","end":"2026-08-12T17:37:52.083148Z","steps":["trace[841104092] 'process raft request' (duration: 113.699774ms)","trace[841104092] 'compare' (duration: 50.984598ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:37:53.012942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.659341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:37:53.013014Z","caller":"traceutil/trace.go:171","msg":"trace[1637882280] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1969; }","duration":"825.152397ms","start":"2026-08-12T17:37:52.187851Z","end":"2026-08-12T17:37:53.013004Z","steps":["trace[1637882280] 'read index received' (duration: 9.334072ms)","trace[1637882280] 'applied index is now lower than readState.Index' (duration: 815.817505ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:37:53.013066Z","caller":"traceutil/trace.go:171","msg":"trace[656587872] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"871.242943ms","start":"2026-08-12T17:37:52.141817Z","end":"2026-08-12T17:37:53.01306Z","steps":["trace[656587872] 'process raft request' (duration: 55.401628ms)","trace[656587872] 'compare' (duration: 815.403694ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:37:53.013099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:52.141802Z","time spent":"871.281805ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:37:53.013202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.333942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77de55a8-761b-4d89-94e8-8be5605bdd82\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-12T17:37:53.013275Z","caller":"traceutil/trace.go:171","msg":"trace[131001691] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-77de55a8-761b-4d89-94e8-8be5605bdd82; range_end:; response_count:1; response_revision:1900; }","duration":"825.437184ms","start":"2026-08-12T17:37:52.187822Z","end":"2026-08-12T17:37:53.013259Z","steps":["trace[131001691] 'agreement among raft nodes before linearized reading' (duration: 825.255749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:53.013363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:52.187809Z","time spent":"825.541726ms","remote":"127.0.0.1:33042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77de55a8-761b-4d89-94e8-8be5605bdd82\" "} {"level":"info","ts":"2026-08-12T17:37:53.097734Z","caller":"traceutil/trace.go:171","msg":"trace[2089985045] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"640.359065ms","start":"2026-08-12T17:37:52.457353Z","end":"2026-08-12T17:37:53.097712Z","steps":["trace[2089985045] 'process raft request' (duration: 640.251222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:53.097837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.922281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:37:53.097894Z","caller":"traceutil/trace.go:171","msg":"trace[160604643] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1901; }","duration":"140.025214ms","start":"2026-08-12T17:37:52.957854Z","end":"2026-08-12T17:37:53.097879Z","steps":["trace[160604643] 'agreement among raft nodes before linearized reading' (duration: 139.902931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:53.097926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:52.457324Z","time spent":"640.486118ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:37:53.09793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.047257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-12T17:37:53.098006Z","caller":"traceutil/trace.go:171","msg":"trace[1603112577] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1901; }","duration":"120.153889ms","start":"2026-08-12T17:37:52.977835Z","end":"2026-08-12T17:37:53.097989Z","steps":["trace[1603112577] 'agreement among raft nodes before linearized reading' (duration: 119.939944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:53.09821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.225772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77de55a8-761b-4d89-94e8-8be5605bdd82\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-12T17:37:53.098211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.173301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18cb1f7635b56721\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-08-12T17:37:53.098246Z","caller":"traceutil/trace.go:171","msg":"trace[1972988122] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-77de55a8-761b-4d89-94e8-8be5605bdd82; range_end:; response_count:1; response_revision:1901; }","duration":"305.297504ms","start":"2026-08-12T17:37:52.792939Z","end":"2026-08-12T17:37:53.098237Z","steps":["trace[1972988122] 'agreement among raft nodes before linearized reading' (duration: 305.200761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:53.098275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:52.792919Z","time spent":"305.348844ms","remote":"127.0.0.1:33042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77de55a8-761b-4d89-94e8-8be5605bdd82\" "} {"level":"info","ts":"2026-08-12T17:37:53.098291Z","caller":"traceutil/trace.go:171","msg":"trace[1373143084] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18cb1f7635b56721; range_end:; response_count:1; response_revision:1901; }","duration":"268.238672ms","start":"2026-08-12T17:37:52.830006Z","end":"2026-08-12T17:37:53.098244Z","steps":["trace[1373143084] 'agreement among raft nodes before linearized reading' (duration: 268.075578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:53.098616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.015667ms","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-08-12T17:37:53.098773Z","caller":"traceutil/trace.go:171","msg":"trace[1893641320] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1901; }","duration":"523.216522ms","start":"2026-08-12T17:37:52.575506Z","end":"2026-08-12T17:37:53.098722Z","steps":["trace[1893641320] 'agreement among raft nodes before linearized reading' (duration: 522.233497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:53.09891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:52.575477Z","time spent":"523.418237ms","remote":"127.0.0.1:46294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-12T17:37:53.384139Z","caller":"traceutil/trace.go:171","msg":"trace[515593908] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"363.840406ms","start":"2026-08-12T17:37:53.020274Z","end":"2026-08-12T17:37:53.384114Z","steps":["trace[515593908] 'process raft request' (duration: 363.677002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:53.384332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:53.02025Z","time spent":"363.96536ms","remote":"127.0.0.1:33042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:37:53.64166Z","caller":"traceutil/trace.go:171","msg":"trace[1399083436] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"537.668613ms","start":"2026-08-12T17:37:53.103894Z","end":"2026-08-12T17:37:53.641563Z","steps":["trace[1399083436] 'process raft request' (duration: 537.586261ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:37:53.641726Z","caller":"traceutil/trace.go:171","msg":"trace[102592677] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"509.356832ms","start":"2026-08-12T17:37:53.132343Z","end":"2026-08-12T17:37:53.641699Z","steps":["trace[102592677] 'process raft request' (duration: 509.196618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:53.641829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:53.103874Z","time spent":"537.871739ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:37:53.641869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:53.13233Z","time spent":"509.463374ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:37:53.641676Z","caller":"traceutil/trace.go:171","msg":"trace[736535355] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"541.074458ms","start":"2026-08-12T17:37:53.100565Z","end":"2026-08-12T17:37:53.64164Z","steps":["trace[736535355] 'process raft request' (duration: 490.120285ms)","trace[736535355] 'compare' (duration: 50.668506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:37:53.64239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:53.100543Z","time spent":"541.746014ms","remote":"127.0.0.1:32926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:37:54.369792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.47601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:37:54.369907Z","caller":"traceutil/trace.go:171","msg":"trace[841657241] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"655.691589ms","start":"2026-08-12T17:37:53.714195Z","end":"2026-08-12T17:37:54.369887Z","steps":["trace[841657241] 'process raft request' (duration: 349.032185ms)","trace[841657241] 'compare' (duration: 306.201913ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:37:54.369971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:53.714172Z","time spent":"655.769211ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:37:54.378248Z","caller":"traceutil/trace.go:171","msg":"trace[1447475953] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"557.201308ms","start":"2026-08-12T17:37:53.821023Z","end":"2026-08-12T17:37:54.378224Z","steps":["trace[1447475953] 'process raft request' (duration: 557.042325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:54.378398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:53.820997Z","time spent":"557.321341ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:37:54.378421Z","caller":"traceutil/trace.go:171","msg":"trace[948481866] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1975; }","duration":"554.756648ms","start":"2026-08-12T17:37:53.823645Z","end":"2026-08-12T17:37:54.378402Z","steps":["trace[948481866] 'read index received' (duration: 239.605314ms)","trace[948481866] 'applied index is now lower than readState.Index' (duration: 315.150144ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:37:54.378519Z","caller":"traceutil/trace.go:171","msg":"trace[760850789] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"315.887542ms","start":"2026-08-12T17:37:54.062613Z","end":"2026-08-12T17:37:54.3785Z","steps":["trace[760850789] 'process raft request' (duration: 315.570284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:54.378644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:54.062587Z","time spent":"315.974925ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:37:54.378652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.998234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-12T17:37:54.378687Z","caller":"traceutil/trace.go:171","msg":"trace[2020268505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1908; }","duration":"555.038575ms","start":"2026-08-12T17:37:53.823638Z","end":"2026-08-12T17:37:54.378677Z","steps":["trace[2020268505] 'agreement among raft nodes before linearized reading' (duration: 554.878471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:54.378717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:53.82359Z","time spent":"555.120956ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T17:37:54.379204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.53014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:37:54.379252Z","caller":"traceutil/trace.go:171","msg":"trace[1530220770] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1909; }","duration":"308.609113ms","start":"2026-08-12T17:37:54.07063Z","end":"2026-08-12T17:37:54.379239Z","steps":["trace[1530220770] 'agreement among raft nodes before linearized reading' (duration: 308.542421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:54.379283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:37:54.070616Z","time spent":"308.658733ms","remote":"127.0.0.1:37096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-08-12T17:37:54.379422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.771007ms","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-08-12T17:37:54.379506Z","caller":"traceutil/trace.go:171","msg":"trace[1273971294] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1909; }","duration":"272.87613ms","start":"2026-08-12T17:37:54.106608Z","end":"2026-08-12T17:37:54.379485Z","steps":["trace[1273971294] 'agreement among raft nodes before linearized reading' (duration: 272.669204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:54.379577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.637623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77de55a8-761b-4d89-94e8-8be5605bdd82\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-08-12T17:37:54.379637Z","caller":"traceutil/trace.go:171","msg":"trace[1888224248] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-77de55a8-761b-4d89-94e8-8be5605bdd82; range_end:; response_count:1; response_revision:1909; }","duration":"276.729355ms","start":"2026-08-12T17:37:54.102894Z","end":"2026-08-12T17:37:54.379624Z","steps":["trace[1888224248] 'agreement among raft nodes before linearized reading' (duration: 276.587912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:54.379758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.696724ms","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-08-12T17:37:54.37979Z","caller":"traceutil/trace.go:171","msg":"trace[270801995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1909; }","duration":"193.751566ms","start":"2026-08-12T17:37:54.186029Z","end":"2026-08-12T17:37:54.37978Z","steps":["trace[270801995] 'agreement among raft nodes before linearized reading' (duration: 193.660724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:37:54.379905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.70025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-12T17:37:54.379884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.46001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-12T17:37:54.379953Z","caller":"traceutil/trace.go:171","msg":"trace[1148205191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1909; }","duration":"292.769321ms","start":"2026-08-12T17:37:54.087172Z","end":"2026-08-12T17:37:54.379941Z","steps":["trace[1148205191] 'agreement among raft nodes before linearized reading' (duration: 292.646678ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:37:54.379962Z","caller":"traceutil/trace.go:171","msg":"trace[1481459226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1909; }","duration":"238.558772ms","start":"2026-08-12T17:37:54.14139Z","end":"2026-08-12T17:37:54.379949Z","steps":["trace[1481459226] 'agreement among raft nodes before linearized reading' (duration: 238.361457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:08.70913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.583417ms","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-08-12T17:38:08.709184Z","caller":"traceutil/trace.go:171","msg":"trace[2069372804] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2035; }","duration":"238.671829ms","start":"2026-08-12T17:38:08.470498Z","end":"2026-08-12T17:38:08.70917Z","steps":["trace[2069372804] 'range keys from in-memory index tree' (duration: 238.474465ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:38:26.67939Z","caller":"traceutil/trace.go:171","msg":"trace[1590958220] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2211; }","duration":"131.312552ms","start":"2026-08-12T17:38:26.548021Z","end":"2026-08-12T17:38:26.679334Z","steps":["trace[1590958220] 'read index received' (duration: 126.8064ms)","trace[1590958220] 'applied index is now lower than readState.Index' (duration: 4.504682ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:38:26.679529Z","caller":"traceutil/trace.go:171","msg":"trace[1322575747] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"150.865995ms","start":"2026-08-12T17:38:26.52865Z","end":"2026-08-12T17:38:26.679516Z","steps":["trace[1322575747] 'process raft request' (duration: 146.122338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:26.679623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.242258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-12T17:38:26.679739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.06069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-12T17:38:26.67977Z","caller":"traceutil/trace.go:171","msg":"trace[1901461404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2135; }","duration":"121.08591ms","start":"2026-08-12T17:38:26.558666Z","end":"2026-08-12T17:38:26.679751Z","steps":["trace[1901461404] 'agreement among raft nodes before linearized reading' (duration: 121.037989ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:38:26.679872Z","caller":"traceutil/trace.go:171","msg":"trace[1013213392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2135; }","duration":"126.3104ms","start":"2026-08-12T17:38:26.55336Z","end":"2026-08-12T17:38:26.679671Z","steps":["trace[1013213392] 'agreement among raft nodes before linearized reading' (duration: 126.166306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:26.679915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.877195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-12T17:38:26.67999Z","caller":"traceutil/trace.go:171","msg":"trace[1337124627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2135; }","duration":"132.008118ms","start":"2026-08-12T17:38:26.547964Z","end":"2026-08-12T17:38:26.679972Z","steps":["trace[1337124627] 'agreement among raft nodes before linearized reading' (duration: 131.782633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:26.68002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.642195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-12T17:38:26.679916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.312836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-12T17:38:26.680073Z","caller":"traceutil/trace.go:171","msg":"trace[1624171949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2135; }","duration":"121.52019ms","start":"2026-08-12T17:38:26.55854Z","end":"2026-08-12T17:38:26.68006Z","steps":["trace[1624171949] 'agreement among raft nodes before linearized reading' (duration: 121.172373ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:38:26.680046Z","caller":"traceutil/trace.go:171","msg":"trace[2065809580] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2135; }","duration":"114.700447ms","start":"2026-08-12T17:38:26.565338Z","end":"2026-08-12T17:38:26.680039Z","steps":["trace[2065809580] 'agreement among raft nodes before linearized reading' (duration: 114.343379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:27.107872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.757439ms","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-08-12T17:38:27.107959Z","caller":"traceutil/trace.go:171","msg":"trace[1268519427] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2139; }","duration":"287.904972ms","start":"2026-08-12T17:38:26.820035Z","end":"2026-08-12T17:38:27.10794Z","steps":["trace[1268519427] 'range keys from in-memory index tree' (duration: 287.577444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:28.211691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260631200688915,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T17:38:28.29822Z","caller":"traceutil/trace.go:171","msg":"trace[1801638361] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"984.161386ms","start":"2026-08-12T17:38:27.314038Z","end":"2026-08-12T17:38:28.298199Z","steps":["trace[1801638361] 'process raft request' (duration: 983.979552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:28.298353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:38:27.314012Z","time spent":"984.270748ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:38:28.550726Z","caller":"traceutil/trace.go:171","msg":"trace[1918980827] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"758.902736ms","start":"2026-08-12T17:38:27.791781Z","end":"2026-08-12T17:38:28.550684Z","steps":["trace[1918980827] 'process raft request' (duration: 740.494981ms)","trace[1918980827] 'compare' (duration: 18.261622ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:38:28.550949Z","caller":"traceutil/trace.go:171","msg":"trace[4855786] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2218; }","duration":"839.340763ms","start":"2026-08-12T17:38:27.711599Z","end":"2026-08-12T17:38:28.55094Z","steps":["trace[4855786] 'read index received' (duration: 586.426912ms)","trace[4855786] 'applied index is now lower than readState.Index' (duration: 252.912741ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:38:28.551104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.503666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T17:38:28.551113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:38:27.791764Z","time spent":"759.025118ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:38:28.551203Z","caller":"traceutil/trace.go:171","msg":"trace[1333174373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2144; }","duration":"839.608049ms","start":"2026-08-12T17:38:27.711555Z","end":"2026-08-12T17:38:28.551163Z","steps":["trace[1333174373] 'agreement among raft nodes before linearized reading' (duration: 839.505396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:28.551287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:38:27.711536Z","time spent":"839.744481ms","remote":"127.0.0.1:32836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T17:38:28.551218Z","caller":"traceutil/trace.go:171","msg":"trace[2103677315] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"115.573786ms","start":"2026-08-12T17:38:28.435628Z","end":"2026-08-12T17:38:28.551202Z","steps":["trace[2103677315] 'process raft request' (duration: 115.168166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:28.551297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.934744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T17:38:28.551402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.930836ms","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-08-12T17:38:28.551442Z","caller":"traceutil/trace.go:171","msg":"trace[819771625] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2144; }","duration":"436.005148ms","start":"2026-08-12T17:38:28.115427Z","end":"2026-08-12T17:38:28.551432Z","steps":["trace[819771625] 'agreement among raft nodes before linearized reading' (duration: 435.890555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:28.551474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:38:28.115408Z","time spent":"436.059489ms","remote":"127.0.0.1:46294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-12T17:38:28.55146Z","caller":"traceutil/trace.go:171","msg":"trace[1864530982] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2144; }","duration":"735.108038ms","start":"2026-08-12T17:38:27.816325Z","end":"2026-08-12T17:38:28.551433Z","steps":["trace[1864530982] 'agreement among raft nodes before linearized reading' (duration: 734.940204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:28.551638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.709984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T17:38:28.551692Z","caller":"traceutil/trace.go:171","msg":"trace[71355180] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2144; }","duration":"562.805246ms","start":"2026-08-12T17:38:27.988875Z","end":"2026-08-12T17:38:28.551681Z","steps":["trace[71355180] 'agreement among raft nodes before linearized reading' (duration: 562.681254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:28.551762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:38:27.988865Z","time spent":"562.884318ms","remote":"127.0.0.1:44048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-12T17:38:28.551879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:38:27.816315Z","time spent":"735.335143ms","remote":"127.0.0.1:57940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-12T17:38:28.981721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.818098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:38:28.981926Z","caller":"traceutil/trace.go:171","msg":"trace[446009601] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"280.146324ms","start":"2026-08-12T17:38:28.701757Z","end":"2026-08-12T17:38:28.981903Z","steps":["trace[446009601] 'read index received' (duration: 109.971308ms)","trace[446009601] 'applied index is now lower than readState.Index' (duration: 170.172426ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:38:28.982091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.324809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-12T17:38:28.982153Z","caller":"traceutil/trace.go:171","msg":"trace[687633954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2145; }","duration":"280.438722ms","start":"2026-08-12T17:38:28.701703Z","end":"2026-08-12T17:38:28.982142Z","steps":["trace[687633954] 'agreement among raft nodes before linearized reading' (duration: 280.270338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:28.982119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.396115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:38:28.982253Z","caller":"traceutil/trace.go:171","msg":"trace[1116696523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2145; }","duration":"270.549738ms","start":"2026-08-12T17:38:28.711686Z","end":"2026-08-12T17:38:28.982236Z","steps":["trace[1116696523] 'agreement among raft nodes before linearized reading' (duration: 270.376234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:28.98251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.375922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-12T17:38:28.98255Z","caller":"traceutil/trace.go:171","msg":"trace[1971866057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2145; }","duration":"269.446404ms","start":"2026-08-12T17:38:28.713095Z","end":"2026-08-12T17:38:28.982541Z","steps":["trace[1971866057] 'agreement among raft nodes before linearized reading' (duration: 269.355111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:28.983003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.024004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-12T17:38:28.983146Z","caller":"traceutil/trace.go:171","msg":"trace[1224301480] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"297.053966ms","start":"2026-08-12T17:38:28.684869Z","end":"2026-08-12T17:38:28.981923Z","steps":["trace[1224301480] 'process raft request' (duration: 126.955081ms)","trace[1224301480] 'compare' (duration: 169.610764ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:38:28.983161Z","caller":"traceutil/trace.go:171","msg":"trace[225106763] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2145; }","duration":"269.971015ms","start":"2026-08-12T17:38:28.713154Z","end":"2026-08-12T17:38:28.983125Z","steps":["trace[225106763] 'agreement among raft nodes before linearized reading' (duration: 268.943562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:28.983458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.574179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-12T17:38:28.983798Z","caller":"traceutil/trace.go:171","msg":"trace[829271711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2145; }","duration":"270.940666ms","start":"2026-08-12T17:38:28.712841Z","end":"2026-08-12T17:38:28.983781Z","steps":["trace[829271711] 'agreement among raft nodes before linearized reading' (duration: 269.691868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:29.555488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260631200688927,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T17:38:29.568686Z","caller":"traceutil/trace.go:171","msg":"trace[811895442] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"582.020309ms","start":"2026-08-12T17:38:28.986633Z","end":"2026-08-12T17:38:29.568653Z","steps":["trace[811895442] 'process raft request' (duration: 581.864386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:29.568887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:38:28.986567Z","time spent":"582.200524ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:38:29.569366Z","caller":"traceutil/trace.go:171","msg":"trace[663663512] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2223; }","duration":"514.540972ms","start":"2026-08-12T17:38:29.054812Z","end":"2026-08-12T17:38:29.569353Z","steps":["trace[663663512] 'read index received' (duration: 513.705203ms)","trace[663663512] 'applied index is now lower than readState.Index' (duration: 834.659µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:38:29.569477Z","caller":"traceutil/trace.go:171","msg":"trace[1226846309] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"581.841356ms","start":"2026-08-12T17:38:28.987625Z","end":"2026-08-12T17:38:29.569467Z","steps":["trace[1226846309] 'process raft request' (duration: 581.659101ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:38:29.569467Z","caller":"traceutil/trace.go:171","msg":"trace[1920857403] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"582.04014ms","start":"2026-08-12T17:38:28.987395Z","end":"2026-08-12T17:38:29.569435Z","steps":["trace[1920857403] 'process raft request' (duration: 581.832455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:29.56953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:38:28.987605Z","time spent":"581.896296ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:38:29.569694Z","caller":"traceutil/trace.go:171","msg":"trace[641293931] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"582.025389ms","start":"2026-08-12T17:38:28.987659Z","end":"2026-08-12T17:38:29.569684Z","steps":["trace[641293931] 'process raft request' (duration: 581.657421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:29.569749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:38:28.987632Z","time spent":"582.089001ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:38:29.569948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.145465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T17:38:29.56998Z","caller":"traceutil/trace.go:171","msg":"trace[636296862] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2149; }","duration":"515.219127ms","start":"2026-08-12T17:38:29.054753Z","end":"2026-08-12T17:38:29.569972Z","steps":["trace[636296862] 'agreement among raft nodes before linearized reading' (duration: 515.088394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:29.570001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:38:29.054738Z","time spent":"515.258097ms","remote":"127.0.0.1:44034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":23,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-08-12T17:38:29.57028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.223136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-12T17:38:29.570346Z","caller":"traceutil/trace.go:171","msg":"trace[1659601179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2149; }","duration":"366.347749ms","start":"2026-08-12T17:38:29.203981Z","end":"2026-08-12T17:38:29.570329Z","steps":["trace[1659601179] 'agreement among raft nodes before linearized reading' (duration: 366.143494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:38:29.570392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:38:29.203953Z","time spent":"366.42981ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-12T17:38:29.570429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:38:28.98736Z","time spent":"582.184933ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:39:31.089122Z","caller":"traceutil/trace.go:171","msg":"trace[981805277] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"471.251643ms","start":"2026-08-12T17:39:30.617859Z","end":"2026-08-12T17:39:31.089111Z","steps":["trace[981805277] 'process raft request' (duration: 471.021649ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:39:31.089281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:39:30.617836Z","time spent":"471.373076ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:39:31.089416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.145002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:39:31.089041Z","caller":"traceutil/trace.go:171","msg":"trace[1716847478] linearizableReadLoop","detail":"{readStateIndex:2673; appliedIndex:2672; }","duration":"376.802555ms","start":"2026-08-12T17:39:30.71222Z","end":"2026-08-12T17:39:31.089022Z","steps":["trace[1716847478] 'read index received' (duration: 376.684333ms)","trace[1716847478] 'applied index is now lower than readState.Index' (duration: 117.582µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:39:31.089574Z","caller":"traceutil/trace.go:171","msg":"trace[876997787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2581; }","duration":"377.343686ms","start":"2026-08-12T17:39:30.71218Z","end":"2026-08-12T17:39:31.089523Z","steps":["trace[876997787] 'agreement among raft nodes before linearized reading' (duration: 377.06659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:39:31.08964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:39:30.712161Z","time spent":"377.469599ms","remote":"127.0.0.1:32836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T17:39:31.089798Z","caller":"traceutil/trace.go:171","msg":"trace[87974039] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"322.890232ms","start":"2026-08-12T17:39:30.766878Z","end":"2026-08-12T17:39:31.089768Z","steps":["trace[87974039] 'process raft request' (duration: 322.718618ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:39:31.089862Z","caller":"traceutil/trace.go:171","msg":"trace[688884417] transaction","detail":"{read_only:false; response_revision:2583; number_of_response:1; }","duration":"320.971462ms","start":"2026-08-12T17:39:30.768851Z","end":"2026-08-12T17:39:31.089823Z","steps":["trace[688884417] 'process raft request' (duration: 320.85103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:39:31.089936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:39:30.766849Z","time spent":"323.014134ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:39:31.090033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:39:30.768839Z","time spent":"321.104274ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:39:31.089943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.767109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-12T17:39:31.090374Z","caller":"traceutil/trace.go:171","msg":"trace[1878747599] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2583; }","duration":"260.229548ms","start":"2026-08-12T17:39:30.830135Z","end":"2026-08-12T17:39:31.090364Z","steps":["trace[1878747599] 'agreement among raft nodes before linearized reading' (duration: 259.611405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:40:04.793069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.344685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:40:04.793216Z","caller":"traceutil/trace.go:171","msg":"trace[918442789] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"664.20791ms","start":"2026-08-12T17:40:04.128981Z","end":"2026-08-12T17:40:04.793189Z","steps":["trace[918442789] 'process raft request' (duration: 235.390558ms)","trace[918442789] 'compare' (duration: 427.000929ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:40:04.793304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:40:04.128967Z","time spent":"664.300284ms","remote":"127.0.0.1:32926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:42:49.174372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.409982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:42:49.174563Z","caller":"traceutil/trace.go:171","msg":"trace[849135142] transaction","detail":"{read_only:false; response_revision:3788; number_of_response:1; }","duration":"123.277479ms","start":"2026-08-12T17:42:49.051194Z","end":"2026-08-12T17:42:49.174472Z","steps":["trace[849135142] 'process raft request' (duration: 15.675561ms)","trace[849135142] 'compare' (duration: 107.232967ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:42:49.184582Z","caller":"traceutil/trace.go:171","msg":"trace[1129100764] transaction","detail":"{read_only:false; response_revision:3789; number_of_response:1; }","duration":"133.168983ms","start":"2026-08-12T17:42:49.051389Z","end":"2026-08-12T17:42:49.184558Z","steps":["trace[1129100764] 'process raft request' (duration: 133.028309ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:42:49.18471Z","caller":"traceutil/trace.go:171","msg":"trace[2082714920] transaction","detail":"{read_only:false; response_revision:3790; number_of_response:1; }","duration":"129.178399ms","start":"2026-08-12T17:42:49.055518Z","end":"2026-08-12T17:42:49.184697Z","steps":["trace[2082714920] 'process raft request' (duration: 128.991064ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:42:49.197522Z","caller":"traceutil/trace.go:171","msg":"trace[1346866393] transaction","detail":"{read_only:false; response_revision:3791; number_of_response:1; }","duration":"128.385336ms","start":"2026-08-12T17:42:49.069114Z","end":"2026-08-12T17:42:49.197499Z","steps":["trace[1346866393] 'process raft request' (duration: 127.629584ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:33.905819Z","caller":"traceutil/trace.go:171","msg":"trace[2089320653] transaction","detail":"{read_only:false; response_revision:4469; number_of_response:1; }","duration":"169.401387ms","start":"2026-08-12T17:43:33.736387Z","end":"2026-08-12T17:43:33.905788Z","steps":["trace[2089320653] 'process raft request' (duration: 167.64452ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:33.925871Z","caller":"traceutil/trace.go:171","msg":"trace[1227717364] linearizableReadLoop","detail":"{readStateIndex:4637; appliedIndex:4634; }","duration":"114.401434ms","start":"2026-08-12T17:43:33.811445Z","end":"2026-08-12T17:43:33.925847Z","steps":["trace[1227717364] 'read index received' (duration: 92.069342ms)","trace[1227717364] 'applied index is now lower than readState.Index' (duration: 22.331292ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:43:33.925983Z","caller":"traceutil/trace.go:171","msg":"trace[1319511415] transaction","detail":"{read_only:false; response_revision:4471; number_of_response:1; }","duration":"187.994088ms","start":"2026-08-12T17:43:33.737979Z","end":"2026-08-12T17:43:33.925973Z","steps":["trace[1319511415] 'process raft request' (duration: 187.803943ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:33.926189Z","caller":"traceutil/trace.go:171","msg":"trace[1293779993] transaction","detail":"{read_only:false; response_revision:4470; number_of_response:1; }","duration":"189.062416ms","start":"2026-08-12T17:43:33.737077Z","end":"2026-08-12T17:43:33.926139Z","steps":["trace[1293779993] 'process raft request' (duration: 188.597014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:43:33.926335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.867547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-08-12T17:43:33.926405Z","caller":"traceutil/trace.go:171","msg":"trace[131173627] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4471; }","duration":"114.979399ms","start":"2026-08-12T17:43:33.811407Z","end":"2026-08-12T17:43:33.926386Z","steps":["trace[131173627] 'agreement among raft nodes before linearized reading' (duration: 114.764993ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:40.251252Z","caller":"traceutil/trace.go:171","msg":"trace[475690565] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"180.551674ms","start":"2026-08-12T17:43:40.070674Z","end":"2026-08-12T17:43:40.251225Z","steps":["trace[475690565] 'process raft request' (duration: 169.668073ms)","trace[475690565] 'compare' (duration: 10.772729ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:43:40.274221Z","caller":"traceutil/trace.go:171","msg":"trace[1372261033] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"159.060891ms","start":"2026-08-12T17:43:40.115143Z","end":"2026-08-12T17:43:40.274204Z","steps":["trace[1372261033] 'process raft request' (duration: 158.964618ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:40.274212Z","caller":"traceutil/trace.go:171","msg":"trace[524482972] transaction","detail":"{read_only:false; response_revision:4609; number_of_response:1; }","duration":"191.19829ms","start":"2026-08-12T17:43:40.082974Z","end":"2026-08-12T17:43:40.274172Z","steps":["trace[524482972] 'process raft request' (duration: 190.999734ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:40.274247Z","caller":"traceutil/trace.go:171","msg":"trace[1693758185] transaction","detail":"{read_only:false; response_revision:4610; number_of_response:1; }","duration":"159.600285ms","start":"2026-08-12T17:43:40.114612Z","end":"2026-08-12T17:43:40.274212Z","steps":["trace[1693758185] 'process raft request' (duration: 159.446491ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:40.274362Z","caller":"traceutil/trace.go:171","msg":"trace[2050437107] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"139.264521ms","start":"2026-08-12T17:43:40.135088Z","end":"2026-08-12T17:43:40.274352Z","steps":["trace[2050437107] 'process raft request' (duration: 139.071666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:43:46.013448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.132697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-08-12T17:43:46.013507Z","caller":"traceutil/trace.go:171","msg":"trace[1202273428] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4662; }","duration":"179.24565ms","start":"2026-08-12T17:43:45.834248Z","end":"2026-08-12T17:43:46.013494Z","steps":["trace[1202273428] 'range keys from in-memory index tree' (duration: 178.955092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:43:46.01354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.707375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5c66d64677-hhsl2.18cb1fc8e1c0d902\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-08-12T17:43:46.013621Z","caller":"traceutil/trace.go:171","msg":"trace[255849857] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5c66d64677-hhsl2.18cb1fc8e1c0d902; range_end:; response_count:1; response_revision:4662; }","duration":"178.795188ms","start":"2026-08-12T17:43:45.834807Z","end":"2026-08-12T17:43:46.013603Z","steps":["trace[255849857] 'range keys from in-memory index tree' (duration: 178.551951ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:46.365632Z","caller":"traceutil/trace.go:171","msg":"trace[31023] linearizableReadLoop","detail":"{readStateIndex:4836; appliedIndex:4835; }","duration":"342.26439ms","start":"2026-08-12T17:43:46.023348Z","end":"2026-08-12T17:43:46.365613Z","steps":["trace[31023] 'read index received' (duration: 342.145137ms)","trace[31023] 'applied index is now lower than readState.Index' (duration: 118.133µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:43:46.365844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.475435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a9138631-2d55-4be5-ab6d-b3944fa11169\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-08-12T17:43:46.365827Z","caller":"traceutil/trace.go:171","msg":"trace[603201276] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"349.951554ms","start":"2026-08-12T17:43:46.015845Z","end":"2026-08-12T17:43:46.365797Z","steps":["trace[603201276] 'process raft request' (duration: 349.594824ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:46.365922Z","caller":"traceutil/trace.go:171","msg":"trace[2136749015] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a9138631-2d55-4be5-ab6d-b3944fa11169; range_end:; response_count:1; response_revision:4663; }","duration":"342.590158ms","start":"2026-08-12T17:43:46.02332Z","end":"2026-08-12T17:43:46.36591Z","steps":["trace[2136749015] 'agreement among raft nodes before linearized reading' (duration: 342.377562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:43:46.365967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:43:46.023306Z","time spent":"342.64858ms","remote":"127.0.0.1:33014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1472,"request content":"key:\"/registry/persistentvolumes/pvc-a9138631-2d55-4be5-ab6d-b3944fa11169\" "} {"level":"warn","ts":"2026-08-12T17:43:46.366075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:43:46.015818Z","time spent":"350.139028ms","remote":"127.0.0.1:32926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:43:46.514693Z","caller":"traceutil/trace.go:171","msg":"trace[74757283] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"484.285746ms","start":"2026-08-12T17:43:46.030373Z","end":"2026-08-12T17:43:46.514658Z","steps":["trace[74757283] 'process raft request' (duration: 473.481679ms)","trace[74757283] 'compare' (duration: 10.619432ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:43:46.514737Z","caller":"traceutil/trace.go:171","msg":"trace[1800590963] linearizableReadLoop","detail":"{readStateIndex:4841; appliedIndex:4836; }","duration":"118.00126ms","start":"2026-08-12T17:43:46.396715Z","end":"2026-08-12T17:43:46.514717Z","steps":["trace[1800590963] 'read index received' (duration: 107.147642ms)","trace[1800590963] 'applied index is now lower than readState.Index' (duration: 10.852678ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:43:46.514858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:43:46.030354Z","time spent":"484.41363ms","remote":"127.0.0.1:33042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13278,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:43:46.514991Z","caller":"traceutil/trace.go:171","msg":"trace[2103486089] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"222.652387ms","start":"2026-08-12T17:43:46.292326Z","end":"2026-08-12T17:43:46.514978Z","steps":["trace[2103486089] 'process raft request' (duration: 222.297817ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:46.515188Z","caller":"traceutil/trace.go:171","msg":"trace[753016531] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"219.927394ms","start":"2026-08-12T17:43:46.295236Z","end":"2026-08-12T17:43:46.515163Z","steps":["trace[753016531] 'process raft request' (duration: 219.42086ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:46.515315Z","caller":"traceutil/trace.go:171","msg":"trace[1022164329] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"219.673107ms","start":"2026-08-12T17:43:46.295631Z","end":"2026-08-12T17:43:46.515304Z","steps":["trace[1022164329] 'process raft request' (duration: 219.052991ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:46.515175Z","caller":"traceutil/trace.go:171","msg":"trace[24273679] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"240.366676ms","start":"2026-08-12T17:43:46.274775Z","end":"2026-08-12T17:43:46.515142Z","steps":["trace[24273679] 'process raft request' (duration: 239.801271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:43:46.515711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.989967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:43:46.515895Z","caller":"traceutil/trace.go:171","msg":"trace[165939314] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4668; }","duration":"119.193451ms","start":"2026-08-12T17:43:46.396675Z","end":"2026-08-12T17:43:46.515868Z","steps":["trace[165939314] 'agreement among raft nodes before linearized reading' (duration: 118.113373ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:47.205866Z","caller":"traceutil/trace.go:171","msg":"trace[1354039750] linearizableReadLoop","detail":"{readStateIndex:4849; appliedIndex:4848; }","duration":"291.585749ms","start":"2026-08-12T17:43:46.914261Z","end":"2026-08-12T17:43:47.205847Z","steps":["trace[1354039750] 'read index received' (duration: 291.437735ms)","trace[1354039750] 'applied index is now lower than readState.Index' (duration: 147.144µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:43:47.205938Z","caller":"traceutil/trace.go:171","msg":"trace[969847366] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"432.170168ms","start":"2026-08-12T17:43:46.773737Z","end":"2026-08-12T17:43:47.205907Z","steps":["trace[969847366] 'process raft request' (duration: 431.936662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:43:47.206039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.770313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-12T17:43:47.206072Z","caller":"traceutil/trace.go:171","msg":"trace[1658925519] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4676; }","duration":"291.852745ms","start":"2026-08-12T17:43:46.91421Z","end":"2026-08-12T17:43:47.206062Z","steps":["trace[1658925519] 'agreement among raft nodes before linearized reading' (duration: 291.730782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:43:47.20606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:43:46.773704Z","time spent":"432.288981ms","remote":"127.0.0.1:46330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2797,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:43:47.212233Z","caller":"traceutil/trace.go:171","msg":"trace[536340617] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"125.921127ms","start":"2026-08-12T17:43:47.086299Z","end":"2026-08-12T17:43:47.212221Z","steps":["trace[536340617] 'process raft request' (duration: 125.67746ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:47.394188Z","caller":"traceutil/trace.go:171","msg":"trace[1475438012] linearizableReadLoop","detail":"{readStateIndex:4855; appliedIndex:4854; }","duration":"105.562667ms","start":"2026-08-12T17:43:47.288604Z","end":"2026-08-12T17:43:47.394167Z","steps":["trace[1475438012] 'read index received' (duration: 66.749468ms)","trace[1475438012] 'applied index is now lower than readState.Index' (duration: 38.812049ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:43:47.394374Z","caller":"traceutil/trace.go:171","msg":"trace[1933080511] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"107.470437ms","start":"2026-08-12T17:43:47.286867Z","end":"2026-08-12T17:43:47.394338Z","steps":["trace[1933080511] 'process raft request' (duration: 68.534435ms)","trace[1933080511] 'compare' (duration: 38.629154ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:43:47.394573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.949487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:120399"} {"level":"info","ts":"2026-08-12T17:43:47.394616Z","caller":"traceutil/trace.go:171","msg":"trace[1504087067] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4681; }","duration":"106.033409ms","start":"2026-08-12T17:43:47.288572Z","end":"2026-08-12T17:43:47.394605Z","steps":["trace[1504087067] 'agreement among raft nodes before linearized reading' (duration: 105.69738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:43:47.397184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.508109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-12T17:43:47.397264Z","caller":"traceutil/trace.go:171","msg":"trace[1415872252] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4683; }","duration":"107.613931ms","start":"2026-08-12T17:43:47.289637Z","end":"2026-08-12T17:43:47.397251Z","steps":["trace[1415872252] 'agreement among raft nodes before linearized reading' (duration: 107.420866ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:47.397765Z","caller":"traceutil/trace.go:171","msg":"trace[640687293] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"109.08891ms","start":"2026-08-12T17:43:47.288653Z","end":"2026-08-12T17:43:47.397742Z","steps":["trace[640687293] 'process raft request' (duration: 108.291999ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:47.398228Z","caller":"traceutil/trace.go:171","msg":"trace[1503403996] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"107.850738ms","start":"2026-08-12T17:43:47.290361Z","end":"2026-08-12T17:43:47.398212Z","steps":["trace[1503403996] 'process raft request' (duration: 106.655086ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:50.757705Z","caller":"traceutil/trace.go:171","msg":"trace[73717701] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"231.44742ms","start":"2026-08-12T17:43:50.526228Z","end":"2026-08-12T17:43:50.757676Z","steps":["trace[73717701] 'process raft request' (duration: 231.222944ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:50.758333Z","caller":"traceutil/trace.go:171","msg":"trace[1927066637] linearizableReadLoop","detail":"{readStateIndex:4937; appliedIndex:4933; }","duration":"191.954738ms","start":"2026-08-12T17:43:50.566356Z","end":"2026-08-12T17:43:50.758311Z","steps":["trace[1927066637] 'read index received' (duration: 191.065505ms)","trace[1927066637] 'applied index is now lower than readState.Index' (duration: 888.123µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:43:50.758638Z","caller":"traceutil/trace.go:171","msg":"trace[197027782] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"226.448599ms","start":"2026-08-12T17:43:50.532168Z","end":"2026-08-12T17:43:50.758616Z","steps":["trace[197027782] 'process raft request' (duration: 225.931736ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:50.758826Z","caller":"traceutil/trace.go:171","msg":"trace[378036968] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"226.09365ms","start":"2026-08-12T17:43:50.532721Z","end":"2026-08-12T17:43:50.758815Z","steps":["trace[378036968] 'process raft request' (duration: 225.498904ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:50.758973Z","caller":"traceutil/trace.go:171","msg":"trace[1927553140] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"225.682009ms","start":"2026-08-12T17:43:50.533283Z","end":"2026-08-12T17:43:50.758965Z","steps":["trace[1927553140] 'process raft request' (duration: 224.98648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:43:50.759191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.826691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-lsmsc.18cb1fc7b59317c5\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-08-12T17:43:50.759231Z","caller":"traceutil/trace.go:171","msg":"trace[540295776] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-lsmsc.18cb1fc7b59317c5; range_end:; response_count:1; response_revision:4763; }","duration":"192.915723ms","start":"2026-08-12T17:43:50.566306Z","end":"2026-08-12T17:43:50.759221Z","steps":["trace[540295776] 'agreement among raft nodes before linearized reading' (duration: 192.78163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:43:50.759573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.636494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7b8f20a6-6eea-4b8b-a4d3-5910cdf9faf0\" ","response":"range_response_count:1 size:3936"} {"level":"info","ts":"2026-08-12T17:43:50.75964Z","caller":"traceutil/trace.go:171","msg":"trace[2011278280] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7b8f20a6-6eea-4b8b-a4d3-5910cdf9faf0; range_end:; response_count:1; response_revision:4763; }","duration":"162.758177ms","start":"2026-08-12T17:43:50.596865Z","end":"2026-08-12T17:43:50.759623Z","steps":["trace[2011278280] 'agreement among raft nodes before linearized reading' (duration: 162.457019ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:51.958012Z","caller":"traceutil/trace.go:171","msg":"trace[271644949] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"101.589459ms","start":"2026-08-12T17:43:51.856389Z","end":"2026-08-12T17:43:51.957979Z","steps":["trace[271644949] 'process raft request' (duration: 99.36898ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:55.823823Z","caller":"traceutil/trace.go:171","msg":"trace[365417124] linearizableReadLoop","detail":"{readStateIndex:5041; appliedIndex:5040; }","duration":"238.848721ms","start":"2026-08-12T17:43:55.584952Z","end":"2026-08-12T17:43:55.823801Z","steps":["trace[365417124] 'read index received' (duration: 179.296997ms)","trace[365417124] 'applied index is now lower than readState.Index' (duration: 59.550844ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:43:55.82385Z","caller":"traceutil/trace.go:171","msg":"trace[52201599] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"290.545208ms","start":"2026-08-12T17:43:55.533291Z","end":"2026-08-12T17:43:55.823836Z","steps":["trace[52201599] 'process raft request' (duration: 230.977264ms)","trace[52201599] 'compare' (duration: 59.42471ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:43:55.824016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.056706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-12T17:43:55.824043Z","caller":"traceutil/trace.go:171","msg":"trace[518424544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4864; }","duration":"239.127768ms","start":"2026-08-12T17:43:55.584907Z","end":"2026-08-12T17:43:55.824035Z","steps":["trace[518424544] 'agreement among raft nodes before linearized reading' (duration: 239.001045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:43:55.824122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.663188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-12T17:43:55.82415Z","caller":"traceutil/trace.go:171","msg":"trace[1603083423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4864; }","duration":"232.71212ms","start":"2026-08-12T17:43:55.591431Z","end":"2026-08-12T17:43:55.824144Z","steps":["trace[1603083423] 'agreement among raft nodes before linearized reading' (duration: 232.641448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:43:55.824289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.924718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:43:55.824318Z","caller":"traceutil/trace.go:171","msg":"trace[919010528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4864; }","duration":"111.99103ms","start":"2026-08-12T17:43:55.71232Z","end":"2026-08-12T17:43:55.824311Z","steps":["trace[919010528] 'agreement among raft nodes before linearized reading' (duration: 111.949309ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:56.361626Z","caller":"traceutil/trace.go:171","msg":"trace[1134799476] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"119.905645ms","start":"2026-08-12T17:43:56.241695Z","end":"2026-08-12T17:43:56.3616Z","steps":["trace[1134799476] 'process raft request' (duration: 33.181581ms)","trace[1134799476] 'compare' (duration: 86.596961ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:43:56.36212Z","caller":"traceutil/trace.go:171","msg":"trace[733514111] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"118.484297ms","start":"2026-08-12T17:43:56.243607Z","end":"2026-08-12T17:43:56.362091Z","steps":["trace[733514111] 'process raft request' (duration: 118.358124ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:56.362118Z","caller":"traceutil/trace.go:171","msg":"trace[2103395250] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"120.179792ms","start":"2026-08-12T17:43:56.241906Z","end":"2026-08-12T17:43:56.362086Z","steps":["trace[2103395250] 'process raft request' (duration: 119.908015ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:56.362343Z","caller":"traceutil/trace.go:171","msg":"trace[174578289] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"118.353484ms","start":"2026-08-12T17:43:56.243973Z","end":"2026-08-12T17:43:56.362327Z","steps":["trace[174578289] 'process raft request' (duration: 118.061206ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:43:56.362433Z","caller":"traceutil/trace.go:171","msg":"trace[1303302964] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"112.765248ms","start":"2026-08-12T17:43:56.249661Z","end":"2026-08-12T17:43:56.362426Z","steps":["trace[1303302964] 'process raft request' (duration: 112.547312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:01.023672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.491397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:44:01.024058Z","caller":"traceutil/trace.go:171","msg":"trace[2030456595] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"329.143583ms","start":"2026-08-12T17:44:00.694877Z","end":"2026-08-12T17:44:01.024021Z","steps":["trace[2030456595] 'process raft request' (duration: 175.197654ms)","trace[2030456595] 'compare' (duration: 153.379504ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:44:01.024169Z","caller":"traceutil/trace.go:171","msg":"trace[977805168] linearizableReadLoop","detail":"{readStateIndex:5082; appliedIndex:5079; }","duration":"218.680378ms","start":"2026-08-12T17:44:00.805466Z","end":"2026-08-12T17:44:01.024146Z","steps":["trace[977805168] 'read index received' (duration: 64.645637ms)","trace[977805168] 'applied index is now lower than readState.Index' (duration: 154.033471ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:44:01.024265Z","caller":"traceutil/trace.go:171","msg":"trace[217711050] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"325.936049ms","start":"2026-08-12T17:44:00.698303Z","end":"2026-08-12T17:44:01.024239Z","steps":["trace[217711050] 'process raft request' (duration: 325.59583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:01.024306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.831603ms","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-08-12T17:44:01.02436Z","caller":"traceutil/trace.go:171","msg":"trace[326330381] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4906; }","duration":"218.930376ms","start":"2026-08-12T17:44:00.805415Z","end":"2026-08-12T17:44:01.024345Z","steps":["trace[326330381] 'agreement among raft nodes before linearized reading' (duration: 218.817803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:01.024494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:00.694854Z","time spent":"329.293656ms","remote":"127.0.0.1:33022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:44:01.024786Z","caller":"traceutil/trace.go:171","msg":"trace[328099735] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"202.27138ms","start":"2026-08-12T17:44:00.822496Z","end":"2026-08-12T17:44:01.024768Z","steps":["trace[328099735] 'process raft request' (duration: 201.534801ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:01.024908Z","caller":"traceutil/trace.go:171","msg":"trace[380784122] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"157.700696ms","start":"2026-08-12T17:44:00.867179Z","end":"2026-08-12T17:44:01.02488Z","steps":["trace[380784122] 'process raft request' (duration: 156.915216ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:01.02522Z","caller":"traceutil/trace.go:171","msg":"trace[122519655] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"329.888292ms","start":"2026-08-12T17:44:00.695313Z","end":"2026-08-12T17:44:01.025201Z","steps":["trace[122519655] 'process raft request' (duration: 328.482045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:01.025317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:00.695282Z","time spent":"329.985264ms","remote":"127.0.0.1:43980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1563,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:44:01.024391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:00.698284Z","time spent":"326.048102ms","remote":"127.0.0.1:44194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:44:01.208603Z","caller":"traceutil/trace.go:171","msg":"trace[1911962551] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"173.077124ms","start":"2026-08-12T17:44:01.035493Z","end":"2026-08-12T17:44:01.20857Z","steps":["trace[1911962551] 'process raft request' (duration: 97.173225ms)","trace[1911962551] 'compare' (duration: 75.750935ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:44:25.491448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.730346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:44:25.491541Z","caller":"traceutil/trace.go:171","msg":"trace[651527165] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:5062; }","duration":"131.864518ms","start":"2026-08-12T17:44:25.359657Z","end":"2026-08-12T17:44:25.491521Z","steps":["trace[651527165] 'count revisions from in-memory index tree' (duration: 131.666083ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:29.549831Z","caller":"traceutil/trace.go:171","msg":"trace[949233325] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"213.282253ms","start":"2026-08-12T17:44:29.336526Z","end":"2026-08-12T17:44:29.549809Z","steps":["trace[949233325] 'process raft request' (duration: 121.957705ms)","trace[949233325] 'compare' (duration: 91.231835ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:44:29.915456Z","caller":"traceutil/trace.go:171","msg":"trace[564367057] linearizableReadLoop","detail":"{readStateIndex:5362; appliedIndex:5361; }","duration":"257.71583ms","start":"2026-08-12T17:44:29.657721Z","end":"2026-08-12T17:44:29.915437Z","steps":["trace[564367057] 'read index received' (duration: 257.555076ms)","trace[564367057] 'applied index is now lower than readState.Index' (duration: 159.904µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:44:29.915511Z","caller":"traceutil/trace.go:171","msg":"trace[1899456594] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"576.487389ms","start":"2026-08-12T17:44:29.338995Z","end":"2026-08-12T17:44:29.915482Z","steps":["trace[1899456594] 'process raft request' (duration: 576.326295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:29.91559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.856813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:44:29.915625Z","caller":"traceutil/trace.go:171","msg":"trace[414427984] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5177; }","duration":"258.004078ms","start":"2026-08-12T17:44:29.657611Z","end":"2026-08-12T17:44:29.915615Z","steps":["trace[414427984] 'agreement among raft nodes before linearized reading' (duration: 257.936436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:29.915656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.11492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T17:44:29.915678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:29.338987Z","time spent":"576.591823ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:44:29.915684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.195099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:44:29.915725Z","caller":"traceutil/trace.go:171","msg":"trace[665633905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5177; }","duration":"204.218453ms","start":"2026-08-12T17:44:29.711489Z","end":"2026-08-12T17:44:29.915708Z","steps":["trace[665633905] 'agreement among raft nodes before linearized reading' (duration: 204.09776ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:29.915738Z","caller":"traceutil/trace.go:171","msg":"trace[331127860] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5177; }","duration":"129.27235ms","start":"2026-08-12T17:44:29.78645Z","end":"2026-08-12T17:44:29.915722Z","steps":["trace[331127860] 'agreement among raft nodes before linearized reading' (duration: 129.191118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:29.915772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.934753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-12T17:44:29.915803Z","caller":"traceutil/trace.go:171","msg":"trace[1447521257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5177; }","duration":"184.996884ms","start":"2026-08-12T17:44:29.730798Z","end":"2026-08-12T17:44:29.915795Z","steps":["trace[1447521257] 'agreement among raft nodes before linearized reading' (duration: 184.893672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:29.91584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.792437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T17:44:29.915845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.424178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"warn","ts":"2026-08-12T17:44:29.915832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.265005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-12T17:44:29.915875Z","caller":"traceutil/trace.go:171","msg":"trace[489432390] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5177; }","duration":"126.843529ms","start":"2026-08-12T17:44:29.78902Z","end":"2026-08-12T17:44:29.915864Z","steps":["trace[489432390] 'agreement among raft nodes before linearized reading' (duration: 126.779267ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:29.915892Z","caller":"traceutil/trace.go:171","msg":"trace[2092539504] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5177; }","duration":"124.51661ms","start":"2026-08-12T17:44:29.791362Z","end":"2026-08-12T17:44:29.915878Z","steps":["trace[2092539504] 'agreement among raft nodes before linearized reading' (duration: 124.340266ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:29.9159Z","caller":"traceutil/trace.go:171","msg":"trace[1123703999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5177; }","duration":"167.379938ms","start":"2026-08-12T17:44:29.748508Z","end":"2026-08-12T17:44:29.915888Z","steps":["trace[1123703999] 'agreement among raft nodes before linearized reading' (duration: 167.169582ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:31.991659Z","caller":"traceutil/trace.go:171","msg":"trace[1027047063] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"783.858318ms","start":"2026-08-12T17:44:31.207772Z","end":"2026-08-12T17:44:31.99163Z","steps":["trace[1027047063] 'process raft request' (duration: 783.671243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:31.991854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:31.207749Z","time spent":"783.995531ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:44:32.36235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.651784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:44:32.362609Z","caller":"traceutil/trace.go:171","msg":"trace[1167474052] linearizableReadLoop","detail":"{readStateIndex:5369; appliedIndex:5367; }","duration":"846.285091ms","start":"2026-08-12T17:44:31.516306Z","end":"2026-08-12T17:44:32.362591Z","steps":["trace[1167474052] 'read index received' (duration: 475.094756ms)","trace[1167474052] 'applied index is now lower than readState.Index' (duration: 371.189255ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:44:32.362688Z","caller":"traceutil/trace.go:171","msg":"trace[510443861] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"807.443107ms","start":"2026-08-12T17:44:31.555234Z","end":"2026-08-12T17:44:32.362678Z","steps":["trace[510443861] 'process raft request' (duration: 807.216111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:32.362751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:31.555208Z","time spent":"807.509309ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:44:32.362829Z","caller":"traceutil/trace.go:171","msg":"trace[1735540180] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"441.396164ms","start":"2026-08-12T17:44:31.921373Z","end":"2026-08-12T17:44:32.362769Z","steps":["trace[1735540180] 'process raft request' (duration: 441.171798ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:32.362865Z","caller":"traceutil/trace.go:171","msg":"trace[1226272649] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"1.095613268s","start":"2026-08-12T17:44:31.267188Z","end":"2026-08-12T17:44:32.362801Z","steps":["trace[1226272649] 'process raft request' (duration: 780.41685ms)","trace[1226272649] 'compare' (duration: 314.044209ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:44:32.362922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.62104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"warn","ts":"2026-08-12T17:44:32.363063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:31.267172Z","time spent":"1.095787882s","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:44:32.363074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:31.921348Z","time spent":"441.61855ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:44:32.363092Z","caller":"traceutil/trace.go:171","msg":"trace[622231597] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5186; }","duration":"846.793044ms","start":"2026-08-12T17:44:31.51628Z","end":"2026-08-12T17:44:32.363073Z","steps":["trace[622231597] 'agreement among raft nodes before linearized reading' (duration: 846.549578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:32.363189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:31.516258Z","time spent":"846.919897ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2010,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-08-12T17:44:32.364912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.475829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T17:44:32.36495Z","caller":"traceutil/trace.go:171","msg":"trace[1646254261] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:5187; }","duration":"779.5475ms","start":"2026-08-12T17:44:31.585392Z","end":"2026-08-12T17:44:32.36494Z","steps":["trace[1646254261] 'agreement among raft nodes before linearized reading' (duration: 779.439457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:32.364971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.60701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-12T17:44:32.36501Z","caller":"traceutil/trace.go:171","msg":"trace[199011547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5187; }","duration":"321.679903ms","start":"2026-08-12T17:44:32.043324Z","end":"2026-08-12T17:44:32.365004Z","steps":["trace[199011547] 'agreement among raft nodes before linearized reading' (duration: 321.56068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:32.365032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:32.043312Z","time spent":"321.715273ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T17:44:32.365041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.707723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-12T17:44:32.365061Z","caller":"traceutil/trace.go:171","msg":"trace[2069466290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5187; }","duration":"368.726973ms","start":"2026-08-12T17:44:31.996328Z","end":"2026-08-12T17:44:32.365055Z","steps":["trace[2069466290] 'agreement among raft nodes before linearized reading' (duration: 368.662831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:32.365009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.219355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-12T17:44:32.365049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.53724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-12T17:44:32.365066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.302022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-12T17:44:32.364978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:31.585379Z","time spent":"779.592342ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-08-12T17:44:32.365124Z","caller":"traceutil/trace.go:171","msg":"trace[494188405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5187; }","duration":"334.399534ms","start":"2026-08-12T17:44:32.030713Z","end":"2026-08-12T17:44:32.365112Z","steps":["trace[494188405] 'agreement among raft nodes before linearized reading' (duration: 334.280241ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:32.365122Z","caller":"traceutil/trace.go:171","msg":"trace[357783535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5187; }","duration":"293.658673ms","start":"2026-08-12T17:44:32.071447Z","end":"2026-08-12T17:44:32.365106Z","steps":["trace[357783535] 'agreement among raft nodes before linearized reading' (duration: 293.427367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:32.365138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.355608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-08-12T17:44:32.365112Z","caller":"traceutil/trace.go:171","msg":"trace[314320986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5187; }","duration":"367.364509ms","start":"2026-08-12T17:44:31.997723Z","end":"2026-08-12T17:44:32.365088Z","steps":["trace[314320986] 'agreement among raft nodes before linearized reading' (duration: 367.128603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:32.365154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:32.030691Z","time spent":"334.456125ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-12T17:44:32.365178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.142648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T17:44:32.365186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:31.997703Z","time spent":"367.473201ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-12T17:44:32.365198Z","caller":"traceutil/trace.go:171","msg":"trace[1347183277] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5187; }","duration":"443.18515ms","start":"2026-08-12T17:44:31.922009Z","end":"2026-08-12T17:44:32.365194Z","steps":["trace[1347183277] 'agreement among raft nodes before linearized reading' (duration: 443.158469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:32.365087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:31.996281Z","time spent":"368.798515ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T17:44:32.365244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.250851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:44:32.365265Z","caller":"traceutil/trace.go:171","msg":"trace[82529870] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5187; }","duration":"443.304332ms","start":"2026-08-12T17:44:31.921956Z","end":"2026-08-12T17:44:32.36526Z","steps":["trace[82529870] 'agreement among raft nodes before linearized reading' (duration: 443.276812ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:32.365156Z","caller":"traceutil/trace.go:171","msg":"trace[800590954] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5187; }","duration":"442.390459ms","start":"2026-08-12T17:44:31.92276Z","end":"2026-08-12T17:44:32.36515Z","steps":["trace[800590954] 'agreement among raft nodes before linearized reading' (duration: 442.339728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:32.365222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:31.922004Z","time spent":"443.21493ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-08-12T17:44:32.365293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:31.922751Z","time spent":"442.536543ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6181,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-08-12T17:44:32.365328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.194087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.157\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-12T17:44:32.365284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:31.921932Z","time spent":"443.348524ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"info","ts":"2026-08-12T17:44:32.365347Z","caller":"traceutil/trace.go:171","msg":"trace[1363693723] range","detail":"{range_begin:/registry/masterleases/199.19.213.157; range_end:; response_count:1; response_revision:5187; }","duration":"585.252778ms","start":"2026-08-12T17:44:31.78009Z","end":"2026-08-12T17:44:32.365343Z","steps":["trace[1363693723] 'agreement among raft nodes before linearized reading' (duration: 585.203507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:32.365367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:31.780073Z","time spent":"585.29052ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.157\" "} {"level":"info","ts":"2026-08-12T17:44:38.353535Z","caller":"traceutil/trace.go:171","msg":"trace[1988912528] linearizableReadLoop","detail":"{readStateIndex:5406; appliedIndex:5405; }","duration":"243.80865ms","start":"2026-08-12T17:44:38.109703Z","end":"2026-08-12T17:44:38.353512Z","steps":["trace[1988912528] 'read index received' (duration: 243.553774ms)","trace[1988912528] 'applied index is now lower than readState.Index' (duration: 253.996µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:44:38.35368Z","caller":"traceutil/trace.go:171","msg":"trace[1498812642] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"340.386252ms","start":"2026-08-12T17:44:38.013281Z","end":"2026-08-12T17:44:38.353667Z","steps":["trace[1498812642] 'process raft request' (duration: 340.081455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:38.353874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.013249Z","time spent":"340.467064ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:44:38.354006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.182076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-12T17:44:38.354126Z","caller":"traceutil/trace.go:171","msg":"trace[452749748] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5218; }","duration":"170.304999ms","start":"2026-08-12T17:44:38.18376Z","end":"2026-08-12T17:44:38.354065Z","steps":["trace[452749748] 'agreement among raft nodes before linearized reading' (duration: 170.109674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:38.354383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.681332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T17:44:38.354421Z","caller":"traceutil/trace.go:171","msg":"trace[2011578228] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5218; }","duration":"244.761714ms","start":"2026-08-12T17:44:38.10965Z","end":"2026-08-12T17:44:38.354412Z","steps":["trace[2011578228] 'agreement among raft nodes before linearized reading' (duration: 244.676612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.316239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.023638ms","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-08-12T17:44:39.316298Z","caller":"traceutil/trace.go:171","msg":"trace[2003393152] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5218; }","duration":"902.158162ms","start":"2026-08-12T17:44:38.414127Z","end":"2026-08-12T17:44:39.316285Z","steps":["trace[2003393152] 'range keys from in-memory index tree' (duration: 901.946537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.316329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.414107Z","time spent":"902.214413ms","remote":"127.0.0.1:44150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-12T17:44:39.317201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.239716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:44:39.317378Z","caller":"traceutil/trace.go:171","msg":"trace[1099910727] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"858.753669ms","start":"2026-08-12T17:44:38.458616Z","end":"2026-08-12T17:44:39.31737Z","steps":["trace[1099910727] 'process raft request' (duration: 858.722988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.31742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.458598Z","time spent":"858.80338ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6144,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:44:39.317635Z","caller":"traceutil/trace.go:171","msg":"trace[488001812] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"902.270905ms","start":"2026-08-12T17:44:38.415356Z","end":"2026-08-12T17:44:39.317627Z","steps":["trace[488001812] 'process raft request' (duration: 45.556988ms)","trace[488001812] 'compare' (duration: 855.560328ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:44:39.317678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.415323Z","time spent":"902.336006ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:44:39.317765Z","caller":"traceutil/trace.go:171","msg":"trace[648480552] linearizableReadLoop","detail":"{readStateIndex:5407; appliedIndex:5406; }","duration":"900.254823ms","start":"2026-08-12T17:44:38.417504Z","end":"2026-08-12T17:44:39.317759Z","steps":["trace[648480552] 'read index received' (duration: 43.424733ms)","trace[648480552] 'applied index is now lower than readState.Index' (duration: 856.8294ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:44:39.317816Z","caller":"traceutil/trace.go:171","msg":"trace[97144870] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"900.143021ms","start":"2026-08-12T17:44:38.417668Z","end":"2026-08-12T17:44:39.317811Z","steps":["trace[97144870] 'process raft request' (duration: 899.588447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.317851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.417658Z","time spent":"900.169811ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:44:39.317943Z","caller":"traceutil/trace.go:171","msg":"trace[628394914] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"899.767122ms","start":"2026-08-12T17:44:38.418167Z","end":"2026-08-12T17:44:39.317934Z","steps":["trace[628394914] 'process raft request' (duration: 899.147356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.317971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.418161Z","time spent":"899.794772ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:44:39.318212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.705745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:44:39.318233Z","caller":"traceutil/trace.go:171","msg":"trace[818928957] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5222; }","duration":"900.727825ms","start":"2026-08-12T17:44:38.417499Z","end":"2026-08-12T17:44:39.318227Z","steps":["trace[818928957] 'agreement among raft nodes before linearized reading' (duration: 900.693414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.318255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.417466Z","time spent":"900.782727ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-08-12T17:44:39.318347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.555781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:44:39.318364Z","caller":"traceutil/trace.go:171","msg":"trace[757645169] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5222; }","duration":"900.582692ms","start":"2026-08-12T17:44:38.417777Z","end":"2026-08-12T17:44:39.318359Z","steps":["trace[757645169] 'agreement among raft nodes before linearized reading' (duration: 900.558011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.318379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.417772Z","time spent":"900.603132ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-08-12T17:44:39.318621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.00357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-12T17:44:39.318647Z","caller":"traceutil/trace.go:171","msg":"trace[928878614] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5222; }","duration":"364.051001ms","start":"2026-08-12T17:44:38.95459Z","end":"2026-08-12T17:44:39.318641Z","steps":["trace[928878614] 'agreement among raft nodes before linearized reading' (duration: 363.962688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.318664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.954579Z","time spent":"364.080572ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-12T17:44:39.318718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.033001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T17:44:39.318734Z","caller":"traceutil/trace.go:171","msg":"trace[1006893056] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:5222; }","duration":"480.073953ms","start":"2026-08-12T17:44:38.838654Z","end":"2026-08-12T17:44:39.318728Z","steps":["trace[1006893056] 'agreement among raft nodes before linearized reading' (duration: 480.047642ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.318752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.838638Z","time spent":"480.108493ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-08-12T17:44:39.318913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.911461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"warn","ts":"2026-08-12T17:44:39.319017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.683459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-12T17:44:39.319007Z","caller":"traceutil/trace.go:171","msg":"trace[281637370] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5222; }","duration":"901.027913ms","start":"2026-08-12T17:44:38.417954Z","end":"2026-08-12T17:44:39.318982Z","steps":["trace[281637370] 'agreement among raft nodes before linearized reading' (duration: 900.826518ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:39.319038Z","caller":"traceutil/trace.go:171","msg":"trace[409587428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5222; }","duration":"839.760861ms","start":"2026-08-12T17:44:38.479272Z","end":"2026-08-12T17:44:39.319032Z","steps":["trace[409587428] 'agreement among raft nodes before linearized reading' (duration: 839.705479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.319059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.479257Z","time spent":"839.798101ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T17:44:39.319043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.537545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-12T17:44:39.319067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.41795Z","time spent":"901.103625ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6181,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"info","ts":"2026-08-12T17:44:39.319096Z","caller":"traceutil/trace.go:171","msg":"trace[626468391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5222; }","duration":"814.638718ms","start":"2026-08-12T17:44:38.504444Z","end":"2026-08-12T17:44:39.319082Z","steps":["trace[626468391] 'agreement among raft nodes before linearized reading' (duration: 814.467263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.319095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.142781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-12T17:44:39.319124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.504425Z","time spent":"814.69336ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-12T17:44:39.319106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.11846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-12T17:44:39.319151Z","caller":"traceutil/trace.go:171","msg":"trace[1454884896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5222; }","duration":"817.232193ms","start":"2026-08-12T17:44:38.501904Z","end":"2026-08-12T17:44:39.319136Z","steps":["trace[1454884896] 'agreement among raft nodes before linearized reading' (duration: 817.056359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.318956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.935257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-12T17:44:39.319181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.501888Z","time spent":"817.287914ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-12T17:44:39.319174Z","caller":"traceutil/trace.go:171","msg":"trace[14927756] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5222; }","duration":"813.216102ms","start":"2026-08-12T17:44:38.505941Z","end":"2026-08-12T17:44:39.319157Z","steps":["trace[14927756] 'agreement among raft nodes before linearized reading' (duration: 813.014397ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:39.319191Z","caller":"traceutil/trace.go:171","msg":"trace[134593216] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5222; }","duration":"636.209194ms","start":"2026-08-12T17:44:38.682974Z","end":"2026-08-12T17:44:39.319183Z","steps":["trace[134593216] 'agreement among raft nodes before linearized reading' (duration: 635.940928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.319215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.682951Z","time spent":"636.257196ms","remote":"127.0.0.1:44240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":40,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-08-12T17:44:39.31922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.505932Z","time spent":"813.279234ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-12T17:44:39.319291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.583212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-12T17:44:39.319313Z","caller":"traceutil/trace.go:171","msg":"trace[1777568001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5222; }","duration":"815.615802ms","start":"2026-08-12T17:44:38.50369Z","end":"2026-08-12T17:44:39.319305Z","steps":["trace[1777568001] 'agreement among raft nodes before linearized reading' (duration: 815.556331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:39.319331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:44:38.503683Z","time spent":"815.642703ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-12T17:44:40.480897Z","caller":"traceutil/trace.go:171","msg":"trace[390726777] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"122.192742ms","start":"2026-08-12T17:44:40.358676Z","end":"2026-08-12T17:44:40.480868Z","steps":["trace[390726777] 'process raft request' (duration: 105.436401ms)","trace[390726777] 'compare' (duration: 16.632938ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:44:41.706018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.139621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-12T17:44:41.706092Z","caller":"traceutil/trace.go:171","msg":"trace[647140654] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5238; }","duration":"101.264604ms","start":"2026-08-12T17:44:41.604807Z","end":"2026-08-12T17:44:41.706072Z","steps":["trace[647140654] 'range keys from in-memory index tree' (duration: 100.939325ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:49.706279Z","caller":"traceutil/trace.go:171","msg":"trace[21201956] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"140.367944ms","start":"2026-08-12T17:44:49.565882Z","end":"2026-08-12T17:44:49.70625Z","steps":["trace[21201956] 'process raft request' (duration: 140.168169ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:49.708534Z","caller":"traceutil/trace.go:171","msg":"trace[1705366675] linearizableReadLoop","detail":"{readStateIndex:5494; appliedIndex:5490; }","duration":"142.145198ms","start":"2026-08-12T17:44:49.566364Z","end":"2026-08-12T17:44:49.708509Z","steps":["trace[1705366675] 'read index received' (duration: 139.615555ms)","trace[1705366675] 'applied index is now lower than readState.Index' (duration: 2.528753ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:44:49.708582Z","caller":"traceutil/trace.go:171","msg":"trace[1313107694] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"142.264161ms","start":"2026-08-12T17:44:49.566263Z","end":"2026-08-12T17:44:49.708527Z","steps":["trace[1313107694] 'process raft request' (duration: 142.005254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:44:49.708752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.362604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11918"} {"level":"info","ts":"2026-08-12T17:44:49.708789Z","caller":"traceutil/trace.go:171","msg":"trace[1640357694] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5305; }","duration":"142.444306ms","start":"2026-08-12T17:44:49.566332Z","end":"2026-08-12T17:44:49.708776Z","steps":["trace[1640357694] 'agreement among raft nodes before linearized reading' (duration: 142.265582ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:49.708806Z","caller":"traceutil/trace.go:171","msg":"trace[1873644764] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"142.443076ms","start":"2026-08-12T17:44:49.566338Z","end":"2026-08-12T17:44:49.708781Z","steps":["trace[1873644764] 'process raft request' (duration: 142.090147ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:49.708943Z","caller":"traceutil/trace.go:171","msg":"trace[945409640] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"142.59672ms","start":"2026-08-12T17:44:49.566336Z","end":"2026-08-12T17:44:49.708933Z","steps":["trace[945409640] 'process raft request' (duration: 142.053376ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:49.70904Z","caller":"traceutil/trace.go:171","msg":"trace[486564681] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"142.491177ms","start":"2026-08-12T17:44:49.566523Z","end":"2026-08-12T17:44:49.709014Z","steps":["trace[486564681] 'process raft request' (duration: 141.949404ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:51.857743Z","caller":"traceutil/trace.go:171","msg":"trace[1635547669] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"141.171239ms","start":"2026-08-12T17:44:51.71655Z","end":"2026-08-12T17:44:51.857721Z","steps":["trace[1635547669] 'process raft request' (duration: 140.949023ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:51.857995Z","caller":"traceutil/trace.go:171","msg":"trace[697856700] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"141.156308ms","start":"2026-08-12T17:44:51.71682Z","end":"2026-08-12T17:44:51.857976Z","steps":["trace[697856700] 'process raft request' (duration: 140.861071ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:51.858274Z","caller":"traceutil/trace.go:171","msg":"trace[1717559592] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"139.867186ms","start":"2026-08-12T17:44:51.718374Z","end":"2026-08-12T17:44:51.858242Z","steps":["trace[1717559592] 'process raft request' (duration: 139.515787ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:44:51.858375Z","caller":"traceutil/trace.go:171","msg":"trace[1454141458] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"139.685952ms","start":"2026-08-12T17:44:51.718665Z","end":"2026-08-12T17:44:51.858351Z","steps":["trace[1454141458] 'process raft request' (duration: 139.407475ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:45:19.800813Z","caller":"traceutil/trace.go:171","msg":"trace[312988724] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"206.836077ms","start":"2026-08-12T17:45:19.593961Z","end":"2026-08-12T17:45:19.800797Z","steps":["trace[312988724] 'process raft request' (duration: 206.739624ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:45:19.805975Z","caller":"traceutil/trace.go:171","msg":"trace[1234893277] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"181.704832ms","start":"2026-08-12T17:45:19.624253Z","end":"2026-08-12T17:45:19.805958Z","steps":["trace[1234893277] 'process raft request' (duration: 181.427845ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:45:19.806358Z","caller":"traceutil/trace.go:171","msg":"trace[1681687522] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"124.039182ms","start":"2026-08-12T17:45:19.682304Z","end":"2026-08-12T17:45:19.806343Z","steps":["trace[1681687522] 'process raft request' (duration: 123.457348ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:45:28.891133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2897} {"level":"info","ts":"2026-08-12T17:45:28.975813Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2897,"took":"83.260005ms","hash":1351435603,"current-db-size-bytes":53665792,"current-db-size":"54 MB","current-db-size-in-use-bytes":29503488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-12T17:45:28.97589Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1351435603,"revision":2897,"compact-revision":-1} {"level":"info","ts":"2026-08-12T17:47:31.698476Z","caller":"traceutil/trace.go:171","msg":"trace[458434032] transaction","detail":"{read_only:false; response_revision:6431; number_of_response:1; }","duration":"176.393112ms","start":"2026-08-12T17:47:31.522055Z","end":"2026-08-12T17:47:31.698448Z","steps":["trace[458434032] 'process raft request' (duration: 176.239869ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:47:31.856976Z","caller":"traceutil/trace.go:171","msg":"trace[662201984] linearizableReadLoop","detail":"{readStateIndex:6660; appliedIndex:6659; }","duration":"144.979883ms","start":"2026-08-12T17:47:31.711963Z","end":"2026-08-12T17:47:31.856943Z","steps":["trace[662201984] 'read index received' (duration: 144.517372ms)","trace[662201984] 'applied index is now lower than readState.Index' (duration: 460.891µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:47:31.857093Z","caller":"traceutil/trace.go:171","msg":"trace[540306933] transaction","detail":"{read_only:false; response_revision:6432; number_of_response:1; }","duration":"152.544839ms","start":"2026-08-12T17:47:31.704515Z","end":"2026-08-12T17:47:31.85706Z","steps":["trace[540306933] 'process raft request' (duration: 152.070268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:31.857172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.188638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:47:31.857225Z","caller":"traceutil/trace.go:171","msg":"trace[181580044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6432; }","duration":"145.26853ms","start":"2026-08-12T17:47:31.711943Z","end":"2026-08-12T17:47:31.857211Z","steps":["trace[181580044] 'agreement among raft nodes before linearized reading' (duration: 145.177618ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:47:40.838645Z","caller":"traceutil/trace.go:171","msg":"trace[384344078] linearizableReadLoop","detail":"{readStateIndex:6714; appliedIndex:6713; }","duration":"169.680459ms","start":"2026-08-12T17:47:40.668944Z","end":"2026-08-12T17:47:40.838625Z","steps":["trace[384344078] 'read index received' (duration: 169.499485ms)","trace[384344078] 'applied index is now lower than readState.Index' (duration: 180.104µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:47:40.838913Z","caller":"traceutil/trace.go:171","msg":"trace[1647634187] transaction","detail":"{read_only:false; response_revision:6483; number_of_response:1; }","duration":"221.481068ms","start":"2026-08-12T17:47:40.617423Z","end":"2026-08-12T17:47:40.838904Z","steps":["trace[1647634187] 'process raft request' (duration: 221.077809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:40.839169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.221081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-12T17:47:40.839209Z","caller":"traceutil/trace.go:171","msg":"trace[26667317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6483; }","duration":"170.298283ms","start":"2026-08-12T17:47:40.668892Z","end":"2026-08-12T17:47:40.839191Z","steps":["trace[26667317] 'agreement among raft nodes before linearized reading' (duration: 170.203741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:40.839407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.812513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-12T17:47:40.839441Z","caller":"traceutil/trace.go:171","msg":"trace[502774838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6483; }","duration":"126.056629ms","start":"2026-08-12T17:47:40.713377Z","end":"2026-08-12T17:47:40.839434Z","steps":["trace[502774838] 'agreement among raft nodes before linearized reading' (duration: 125.983257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:40.839645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.155298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-12T17:47:40.839669Z","caller":"traceutil/trace.go:171","msg":"trace[931763726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6483; }","duration":"122.21015ms","start":"2026-08-12T17:47:40.717453Z","end":"2026-08-12T17:47:40.839664Z","steps":["trace[931763726] 'agreement among raft nodes before linearized reading' (duration: 122.147219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:40.839826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.340493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-12T17:47:40.839864Z","caller":"traceutil/trace.go:171","msg":"trace[334191613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6483; }","duration":"122.395144ms","start":"2026-08-12T17:47:40.717453Z","end":"2026-08-12T17:47:40.839848Z","steps":["trace[334191613] 'agreement among raft nodes before linearized reading' (duration: 122.295652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:40.839897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.353533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:47:40.840297Z","caller":"traceutil/trace.go:171","msg":"trace[1120371297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6483; }","duration":"125.740631ms","start":"2026-08-12T17:47:40.714493Z","end":"2026-08-12T17:47:40.840234Z","steps":["trace[1120371297] 'agreement among raft nodes before linearized reading' (duration: 125.323432ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:47:41.24204Z","caller":"traceutil/trace.go:171","msg":"trace[1541092868] transaction","detail":"{read_only:false; response_revision:6484; number_of_response:1; }","duration":"396.633022ms","start":"2026-08-12T17:47:40.845384Z","end":"2026-08-12T17:47:41.242017Z","steps":["trace[1541092868] 'process raft request' (duration: 396.473109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:41.24218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:47:40.845374Z","time spent":"396.738015ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:47:41.242793Z","caller":"traceutil/trace.go:171","msg":"trace[677986870] linearizableReadLoop","detail":"{readStateIndex:6718; appliedIndex:6714; }","duration":"166.064344ms","start":"2026-08-12T17:47:41.076706Z","end":"2026-08-12T17:47:41.24277Z","steps":["trace[677986870] 'read index received' (duration: 165.225114ms)","trace[677986870] 'applied index is now lower than readState.Index' (duration: 838.33µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:47:41.242832Z","caller":"traceutil/trace.go:171","msg":"trace[1564020599] transaction","detail":"{read_only:false; response_revision:6487; number_of_response:1; }","duration":"396.842767ms","start":"2026-08-12T17:47:40.845972Z","end":"2026-08-12T17:47:41.242815Z","steps":["trace[1564020599] 'process raft request' (duration: 396.733894ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:47:41.242831Z","caller":"traceutil/trace.go:171","msg":"trace[300695894] transaction","detail":"{read_only:false; response_revision:6486; number_of_response:1; }","duration":"396.96ms","start":"2026-08-12T17:47:40.845838Z","end":"2026-08-12T17:47:41.242798Z","steps":["trace[300695894] 'process raft request' (duration: 396.830767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:41.242918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:47:40.845954Z","time spent":"396.925468ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:47:41.242999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:47:40.845819Z","time spent":"397.090513ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:47:41.243179Z","caller":"traceutil/trace.go:171","msg":"trace[913648434] transaction","detail":"{read_only:false; response_revision:6485; number_of_response:1; }","duration":"397.323058ms","start":"2026-08-12T17:47:40.845792Z","end":"2026-08-12T17:47:41.243115Z","steps":["trace[913648434] 'process raft request' (duration: 396.756045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:41.243249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.528755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T17:47:41.243493Z","caller":"traceutil/trace.go:171","msg":"trace[284900373] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:6488; }","duration":"166.786791ms","start":"2026-08-12T17:47:41.076682Z","end":"2026-08-12T17:47:41.243469Z","steps":["trace[284900373] 'agreement among raft nodes before linearized reading' (duration: 166.189307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:41.244523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:47:40.845784Z","time spent":"397.481291ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:47:41.98597Z","caller":"traceutil/trace.go:171","msg":"trace[2031843964] transaction","detail":"{read_only:false; response_revision:6490; number_of_response:1; }","duration":"137.277017ms","start":"2026-08-12T17:47:41.848668Z","end":"2026-08-12T17:47:41.985945Z","steps":["trace[2031843964] 'process raft request' (duration: 122.216698ms)","trace[2031843964] 'compare' (duration: 14.786273ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:47:41.986248Z","caller":"traceutil/trace.go:171","msg":"trace[1181876985] linearizableReadLoop","detail":"{readStateIndex:6722; appliedIndex:6721; }","duration":"136.462539ms","start":"2026-08-12T17:47:41.849766Z","end":"2026-08-12T17:47:41.986228Z","steps":["trace[1181876985] 'read index received' (duration: 121.081763ms)","trace[1181876985] 'applied index is now lower than readState.Index' (duration: 15.379196ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:47:41.986485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.686324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-12T17:47:41.986621Z","caller":"traceutil/trace.go:171","msg":"trace[670458420] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:6490; }","duration":"136.865318ms","start":"2026-08-12T17:47:41.849741Z","end":"2026-08-12T17:47:41.986606Z","steps":["trace[670458420] 'agreement among raft nodes before linearized reading' (duration: 136.609972ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:47:42.530657Z","caller":"traceutil/trace.go:171","msg":"trace[8246156] transaction","detail":"{read_only:false; response_revision:6491; number_of_response:1; }","duration":"183.455785ms","start":"2026-08-12T17:47:42.347143Z","end":"2026-08-12T17:47:42.530599Z","steps":["trace[8246156] 'process raft request' (duration: 183.20846ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:47:42.631137Z","caller":"traceutil/trace.go:171","msg":"trace[1170798031] transaction","detail":"{read_only:false; response_revision:6492; number_of_response:1; }","duration":"226.457992ms","start":"2026-08-12T17:47:42.404651Z","end":"2026-08-12T17:47:42.631109Z","steps":["trace[1170798031] 'process raft request' (duration: 189.024675ms)","trace[1170798031] 'compare' (duration: 37.298143ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:47:42.631303Z","caller":"traceutil/trace.go:171","msg":"trace[1123458463] linearizableReadLoop","detail":"{readStateIndex:6725; appliedIndex:6722; }","duration":"146.886289ms","start":"2026-08-12T17:47:42.484383Z","end":"2026-08-12T17:47:42.631269Z","steps":["trace[1123458463] 'read index received' (duration: 45.930913ms)","trace[1123458463] 'applied index is now lower than readState.Index' (duration: 100.953856ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:47:42.631411Z","caller":"traceutil/trace.go:171","msg":"trace[1255178788] transaction","detail":"{read_only:false; response_revision:6493; number_of_response:1; }","duration":"226.182645ms","start":"2026-08-12T17:47:42.405204Z","end":"2026-08-12T17:47:42.631387Z","steps":["trace[1255178788] 'process raft request' (duration: 225.866448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:42.631471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.073784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:47:42.631556Z","caller":"traceutil/trace.go:171","msg":"trace[1208763193] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:6493; }","duration":"147.218728ms","start":"2026-08-12T17:47:42.484323Z","end":"2026-08-12T17:47:42.631542Z","steps":["trace[1208763193] 'agreement among raft nodes before linearized reading' (duration: 147.088605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:42.631603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.041656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-12T17:47:42.631641Z","caller":"traceutil/trace.go:171","msg":"trace[1501471697] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6493; }","duration":"113.076887ms","start":"2026-08-12T17:47:42.518554Z","end":"2026-08-12T17:47:42.631631Z","steps":["trace[1501471697] 'agreement among raft nodes before linearized reading' (duration: 112.946444ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:50:28.898748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5615} {"level":"info","ts":"2026-08-12T17:50:29.018564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5615,"took":"118.367894ms","hash":1528451948,"current-db-size-bytes":53665792,"current-db-size":"54 MB","current-db-size-in-use-bytes":18313216,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-12T17:50:29.018624Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1528451948,"revision":5615,"compact-revision":2897}