{"level":"warn","ts":"2026-06-09T15:43:52.011674Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T15:43:52.011766Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-06-09T15:43:52.011836Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T15:43:52.011848Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-06-09T15:43:52.011864Z","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-06-09T15:43:52.012654Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-06-09T15:43:52.012765Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-06-09T15:43:52.017802Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.811528ms"} {"level":"info","ts":"2026-06-09T15:43:52.025982Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-06-09T15:43:52.026245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T15:43:52.026378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-06-09T15:43:52.026517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T15:43:52.026577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-06-09T15:43:52.026665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-06-09T15:43:52.032872Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T15:43:52.034258Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T15:43:52.035323Z","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-06-09T15:43:52.038429Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T15:43:52.042224Z","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-06-09T15:43:52.042317Z","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-06-09T15:43:52.042329Z","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-06-09T15:43:52.042439Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T15:43:52.043261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-06-09T15:43:52.043391Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-06-09T15:43:52.044504Z","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-06-09T15:43:52.044609Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-06-09T15:43:52.044668Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-06-09T15:43:52.045011Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T15:43:52.045082Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T15:43:52.827748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T15:43:52.8278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T15:43:52.827844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-06-09T15:43:52.827858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-06-09T15:43:52.827865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-06-09T15:43:52.827877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-06-09T15:43:52.827888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-06-09T15:43:52.82967Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T15:43:52.830302Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T15:43:52.830303Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T15:43:52.830331Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T15:43:52.8305Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T15:43:52.830536Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T15:43:52.831891Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-06-09T15:43:52.832702Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T15:43:52.837125Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T15:43:52.837238Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T15:43:52.83727Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T15:44:15.817783Z","caller":"traceutil/trace.go:171","msg":"trace[240595990] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"109.382363ms","start":"2026-06-09T15:44:15.708378Z","end":"2026-06-09T15:44:15.817761Z","steps":["trace[240595990] 'process raft request' (duration: 109.246598ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:20.850975Z","caller":"traceutil/trace.go:171","msg":"trace[374657676] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"488.709502ms","start":"2026-06-09T15:44:20.362242Z","end":"2026-06-09T15:44:20.850952Z","steps":["trace[374657676] 'read index received' (duration: 488.532445ms)","trace[374657676] 'applied index is now lower than readState.Index' (duration: 176.197µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:44:20.851317Z","caller":"traceutil/trace.go:171","msg":"trace[151361168] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"714.234063ms","start":"2026-06-09T15:44:20.137071Z","end":"2026-06-09T15:44:20.851305Z","steps":["trace[151361168] 'process raft request' (duration: 713.760774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:20.851855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:20.137053Z","time spent":"714.302226ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:44:20.852074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.828056ms","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-06-09T15:44:20.85222Z","caller":"traceutil/trace.go:171","msg":"trace[308756236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"490.001563ms","start":"2026-06-09T15:44:20.362204Z","end":"2026-06-09T15:44:20.852206Z","steps":["trace[308756236] 'agreement among raft nodes before linearized reading' (duration: 489.831766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:20.852287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:20.362184Z","time spent":"490.090577ms","remote":"127.0.0.1:44606","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-06-09T15:44:20.852447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.636181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T15:44:20.852491Z","caller":"traceutil/trace.go:171","msg":"trace[1156187964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"480.701413ms","start":"2026-06-09T15:44:20.371782Z","end":"2026-06-09T15:44:20.852483Z","steps":["trace[1156187964] 'agreement among raft nodes before linearized reading' (duration: 480.636561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:20.852518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:20.37177Z","time spent":"480.742184ms","remote":"127.0.0.1:44606","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-06-09T15:44:20.852707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.014849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:44:20.852748Z","caller":"traceutil/trace.go:171","msg":"trace[152457359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"122.087512ms","start":"2026-06-09T15:44:20.730646Z","end":"2026-06-09T15:44:20.852733Z","steps":["trace[152457359] 'agreement among raft nodes before linearized reading' (duration: 122.0396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:20.852881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.704618ms","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-06-09T15:44:20.852911Z","caller":"traceutil/trace.go:171","msg":"trace[812148412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:476; }","duration":"344.734999ms","start":"2026-06-09T15:44:20.508168Z","end":"2026-06-09T15:44:20.852903Z","steps":["trace[812148412] 'agreement among raft nodes before linearized reading' (duration: 344.684377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:20.852935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:20.508106Z","time spent":"344.823562ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-09T15:44:27.578877Z","caller":"traceutil/trace.go:171","msg":"trace[1021130769] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"128.411709ms","start":"2026-06-09T15:44:27.450436Z","end":"2026-06-09T15:44:27.578848Z","steps":["trace[1021130769] 'process raft request' (duration: 128.092068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:28.258709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.180423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:44:28.258862Z","caller":"traceutil/trace.go:171","msg":"trace[1494006432] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"387.676412ms","start":"2026-06-09T15:44:27.871173Z","end":"2026-06-09T15:44:28.258849Z","steps":["trace[1494006432] 'process raft request' (duration: 387.61585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:28.258928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:27.871136Z","time spent":"387.757925ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:44:28.259013Z","caller":"traceutil/trace.go:171","msg":"trace[1382527972] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"652.473133ms","start":"2026-06-09T15:44:27.606449Z","end":"2026-06-09T15:44:28.258922Z","steps":["trace[1382527972] 'process raft request' (duration: 43.918366ms)","trace[1382527972] 'compare' (duration: 608.033178ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:44:28.259211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:27.606425Z","time spent":"652.710692ms","remote":"127.0.0.1:44532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:44:30.496132Z","caller":"traceutil/trace.go:171","msg":"trace[1907582557] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"344.689175ms","start":"2026-06-09T15:44:30.15139Z","end":"2026-06-09T15:44:30.496079Z","steps":["trace[1907582557] 'process raft request' (duration: 344.532309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:30.496513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:30.151374Z","time spent":"345.052147ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:44:30.51566Z","caller":"traceutil/trace.go:171","msg":"trace[918721025] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"357.347406ms","start":"2026-06-09T15:44:30.158291Z","end":"2026-06-09T15:44:30.515638Z","steps":["trace[918721025] 'process raft request' (duration: 352.97649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:30.515767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:30.158273Z","time spent":"357.44417ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:44:30.515847Z","caller":"traceutil/trace.go:171","msg":"trace[1490556943] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"351.354952ms","start":"2026-06-09T15:44:30.164443Z","end":"2026-06-09T15:44:30.515798Z","steps":["trace[1490556943] 'process raft request' (duration: 351.123703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:30.51603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:30.164431Z","time spent":"351.515827ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:44:30.51609Z","caller":"traceutil/trace.go:171","msg":"trace[602033469] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"343.532672ms","start":"2026-06-09T15:44:30.172539Z","end":"2026-06-09T15:44:30.516072Z","steps":["trace[602033469] 'process raft request' (duration: 343.345746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:30.51618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:30.17253Z","time spent":"343.606205ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:44:30.516603Z","caller":"traceutil/trace.go:171","msg":"trace[1625516598] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"327.307654ms","start":"2026-06-09T15:44:30.189267Z","end":"2026-06-09T15:44:30.516575Z","steps":["trace[1625516598] 'process raft request' (duration: 327.161809ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:30.516682Z","caller":"traceutil/trace.go:171","msg":"trace[1914914492] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"327.115467ms","start":"2026-06-09T15:44:30.18955Z","end":"2026-06-09T15:44:30.516665Z","steps":["trace[1914914492] 'process raft request' (duration: 326.977702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:30.516778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:30.18952Z","time spent":"327.216181ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:44:30.516788Z","caller":"traceutil/trace.go:171","msg":"trace[887840184] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"327.103717ms","start":"2026-06-09T15:44:30.189667Z","end":"2026-06-09T15:44:30.516771Z","steps":["trace[887840184] 'process raft request' (duration: 326.948081ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:30.51682Z","caller":"traceutil/trace.go:171","msg":"trace[802962972] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"320.126889ms","start":"2026-06-09T15:44:30.196672Z","end":"2026-06-09T15:44:30.516799Z","steps":["trace[802962972] 'process raft request' (duration: 319.991324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:30.516856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:30.189649Z","time spent":"327.17592ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T15:44:30.516871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:30.189236Z","time spent":"327.4706ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:44:30.516934Z","caller":"traceutil/trace.go:171","msg":"trace[702935591] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"312.32581ms","start":"2026-06-09T15:44:30.204601Z","end":"2026-06-09T15:44:30.516927Z","steps":["trace[702935591] 'process raft request' (duration: 312.108423ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:30.516982Z","caller":"traceutil/trace.go:171","msg":"trace[788284269] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"289.494397ms","start":"2026-06-09T15:44:30.227478Z","end":"2026-06-09T15:44:30.516972Z","steps":["trace[788284269] 'process raft request' (duration: 289.28889ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:30.517052Z","caller":"traceutil/trace.go:171","msg":"trace[390581662] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"179.790938ms","start":"2026-06-09T15:44:30.337244Z","end":"2026-06-09T15:44:30.517035Z","steps":["trace[390581662] 'process raft request' (duration: 179.729035ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:30.517094Z","caller":"traceutil/trace.go:171","msg":"trace[151758516] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"289.56142ms","start":"2026-06-09T15:44:30.227517Z","end":"2026-06-09T15:44:30.517079Z","steps":["trace[151758516] 'process raft request' (duration: 289.30425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:30.516987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:30.204579Z","time spent":"312.390903ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T15:44:30.516925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:30.196644Z","time spent":"320.242003ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:44:33.23995Z","caller":"traceutil/trace.go:171","msg":"trace[1984053186] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"194.522452ms","start":"2026-06-09T15:44:33.045401Z","end":"2026-06-09T15:44:33.239924Z","steps":["trace[1984053186] 'process raft request' (duration: 194.348766ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:33.240177Z","caller":"traceutil/trace.go:171","msg":"trace[1175406751] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"187.578353ms","start":"2026-06-09T15:44:33.052573Z","end":"2026-06-09T15:44:33.240151Z","steps":["trace[1175406751] 'read index received' (duration: 11.550708ms)","trace[1175406751] 'applied index is now lower than readState.Index' (duration: 176.026395ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:44:33.240368Z","caller":"traceutil/trace.go:171","msg":"trace[394230479] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"183.915937ms","start":"2026-06-09T15:44:33.05644Z","end":"2026-06-09T15:44:33.240356Z","steps":["trace[394230479] 'process raft request' (duration: 183.4478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:33.240491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.306446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:44:33.240564Z","caller":"traceutil/trace.go:171","msg":"trace[569887245] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:615; }","duration":"174.43481ms","start":"2026-06-09T15:44:33.066115Z","end":"2026-06-09T15:44:33.240549Z","steps":["trace[569887245] 'agreement among raft nodes before linearized reading' (duration: 174.312256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:33.240571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.498554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:44:33.24057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.353442ms","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-06-09T15:44:33.24061Z","caller":"traceutil/trace.go:171","msg":"trace[1432620583] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:615; }","duration":"128.550446ms","start":"2026-06-09T15:44:33.112048Z","end":"2026-06-09T15:44:33.240598Z","steps":["trace[1432620583] 'agreement among raft nodes before linearized reading' (duration: 128.435342ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:33.240638Z","caller":"traceutil/trace.go:171","msg":"trace[14214263] 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":"153.468376ms","start":"2026-06-09T15:44:33.087149Z","end":"2026-06-09T15:44:33.240618Z","steps":["trace[14214263] 'agreement among raft nodes before linearized reading' (duration: 153.341331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:33.240773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.594566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:44:33.240784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.209914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:44:33.240837Z","caller":"traceutil/trace.go:171","msg":"trace[471057606] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:615; }","duration":"188.285367ms","start":"2026-06-09T15:44:33.052542Z","end":"2026-06-09T15:44:33.240828Z","steps":["trace[471057606] 'agreement among raft nodes before linearized reading' (duration: 188.215215ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:33.240844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.754856ms","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-06-09T15:44:33.240888Z","caller":"traceutil/trace.go:171","msg":"trace[1681156026] 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":"153.794757ms","start":"2026-06-09T15:44:33.087077Z","end":"2026-06-09T15:44:33.240872Z","steps":["trace[1681156026] 'agreement among raft nodes before linearized reading' (duration: 153.730545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:33.240909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.53432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:44:33.24096Z","caller":"traceutil/trace.go:171","msg":"trace[1550665370] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:615; }","duration":"129.595982ms","start":"2026-06-09T15:44:33.111349Z","end":"2026-06-09T15:44:33.240945Z","steps":["trace[1550665370] 'agreement among raft nodes before linearized reading' (duration: 129.509089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:33.241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.049982ms","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-06-09T15:44:33.241033Z","caller":"traceutil/trace.go:171","msg":"trace[1850972003] 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":"153.086064ms","start":"2026-06-09T15:44:33.087938Z","end":"2026-06-09T15:44:33.241024Z","steps":["trace[1850972003] 'agreement among raft nodes before linearized reading' (duration: 153.034082ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:33.240849Z","caller":"traceutil/trace.go:171","msg":"trace[1449136151] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:615; }","duration":"174.678558ms","start":"2026-06-09T15:44:33.066159Z","end":"2026-06-09T15:44:33.240837Z","steps":["trace[1449136151] 'agreement among raft nodes before linearized reading' (duration: 174.580785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:33.241153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.807999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:44:33.241182Z","caller":"traceutil/trace.go:171","msg":"trace[1607432787] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:615; }","duration":"129.874012ms","start":"2026-06-09T15:44:33.1113Z","end":"2026-06-09T15:44:33.241174Z","steps":["trace[1607432787] 'agreement among raft nodes before linearized reading' (duration: 129.81381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:33.24076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.45234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:44:33.241384Z","caller":"traceutil/trace.go:171","msg":"trace[601655680] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:615; }","duration":"175.104413ms","start":"2026-06-09T15:44:33.066269Z","end":"2026-06-09T15:44:33.241373Z","steps":["trace[601655680] 'agreement among raft nodes before linearized reading' (duration: 174.421189ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:33.845035Z","caller":"traceutil/trace.go:171","msg":"trace[137772014] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"113.504525ms","start":"2026-06-09T15:44:33.731506Z","end":"2026-06-09T15:44:33.84501Z","steps":["trace[137772014] 'read index received' (duration: 112.637956ms)","trace[137772014] 'applied index is now lower than readState.Index' (duration: 787.357µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:44:33.845103Z","caller":"traceutil/trace.go:171","msg":"trace[1979343297] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"209.615363ms","start":"2026-06-09T15:44:33.635456Z","end":"2026-06-09T15:44:33.845071Z","steps":["trace[1979343297] 'process raft request' (duration: 208.931118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:33.845206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.688833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:44:33.845251Z","caller":"traceutil/trace.go:171","msg":"trace[1118157293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"113.814987ms","start":"2026-06-09T15:44:33.731425Z","end":"2026-06-09T15:44:33.84524Z","steps":["trace[1118157293] 'agreement among raft nodes before linearized reading' (duration: 113.682343ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:34.409646Z","caller":"traceutil/trace.go:171","msg":"trace[431720054] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"110.663778ms","start":"2026-06-09T15:44:34.298954Z","end":"2026-06-09T15:44:34.409618Z","steps":["trace[431720054] 'process raft request' (duration: 109.885812ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:34.698988Z","caller":"traceutil/trace.go:171","msg":"trace[322573096] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"232.31938ms","start":"2026-06-09T15:44:34.466641Z","end":"2026-06-09T15:44:34.69896Z","steps":["trace[322573096] 'process raft request' (duration: 175.886425ms)","trace[322573096] 'compare' (duration: 56.307701ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:44:38.2305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933266405530722182,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T15:44:38.234615Z","caller":"traceutil/trace.go:171","msg":"trace[1468553471] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"505.19637ms","start":"2026-06-09T15:44:37.72939Z","end":"2026-06-09T15:44:38.234586Z","steps":["trace[1468553471] 'read index received' (duration: 504.378964ms)","trace[1468553471] 'applied index is now lower than readState.Index' (duration: 816.376µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:44:38.23513Z","caller":"traceutil/trace.go:171","msg":"trace[627565991] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"531.944296ms","start":"2026-06-09T15:44:37.703158Z","end":"2026-06-09T15:44:38.235102Z","steps":["trace[627565991] 'process raft request' (duration: 530.888841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:38.235152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.671276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:44:38.235249Z","caller":"traceutil/trace.go:171","msg":"trace[1280484518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"505.839092ms","start":"2026-06-09T15:44:37.729355Z","end":"2026-06-09T15:44:38.235194Z","steps":["trace[1280484518] 'agreement among raft nodes before linearized reading' (duration: 505.331724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:38.235292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:37.70312Z","time spent":"532.08129ms","remote":"127.0.0.1:44406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:44:38.235342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:37.729338Z","time spent":"505.955426ms","remote":"127.0.0.1:44298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T15:44:38.413417Z","caller":"traceutil/trace.go:171","msg":"trace[1944867351] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"119.568054ms","start":"2026-06-09T15:44:38.293799Z","end":"2026-06-09T15:44:38.413367Z","steps":["trace[1944867351] 'process raft request' (duration: 119.308355ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:38.876077Z","caller":"traceutil/trace.go:171","msg":"trace[88561879] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"254.977706ms","start":"2026-06-09T15:44:38.621072Z","end":"2026-06-09T15:44:38.87605Z","steps":["trace[88561879] 'process raft request' (duration: 254.864662ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:38.876418Z","caller":"traceutil/trace.go:171","msg":"trace[488077966] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"447.063363ms","start":"2026-06-09T15:44:38.429337Z","end":"2026-06-09T15:44:38.876401Z","steps":["trace[488077966] 'process raft request' (duration: 422.5477ms)","trace[488077966] 'compare' (duration: 23.418586ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:44:38.876559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:38.429325Z","time spent":"447.138726ms","remote":"127.0.0.1:44504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:44:38.876629Z","caller":"traceutil/trace.go:171","msg":"trace[1518945333] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"446.492804ms","start":"2026-06-09T15:44:38.430074Z","end":"2026-06-09T15:44:38.876567Z","steps":["trace[1518945333] 'read index received' (duration: 421.821246ms)","trace[1518945333] 'applied index is now lower than readState.Index' (duration: 24.666928ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:44:38.876915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.816566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:44:38.877016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.280352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:44:38.877075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.652963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:44:38.876995Z","caller":"traceutil/trace.go:171","msg":"trace[1471348190] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:648; }","duration":"446.933428ms","start":"2026-06-09T15:44:38.43004Z","end":"2026-06-09T15:44:38.876974Z","steps":["trace[1471348190] 'agreement among raft nodes before linearized reading' (duration: 446.812185ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:38.877135Z","caller":"traceutil/trace.go:171","msg":"trace[2079844674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"143.744736ms","start":"2026-06-09T15:44:38.733371Z","end":"2026-06-09T15:44:38.877116Z","steps":["trace[2079844674] 'agreement among raft nodes before linearized reading' (duration: 143.631842ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:38.877143Z","caller":"traceutil/trace.go:171","msg":"trace[587633979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"147.458177ms","start":"2026-06-09T15:44:38.729637Z","end":"2026-06-09T15:44:38.877095Z","steps":["trace[587633979] 'agreement among raft nodes before linearized reading' (duration: 147.23012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:38.877238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:38.430023Z","time spent":"447.198957ms","remote":"127.0.0.1:44468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-06-09T15:44:39.471989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933266405530722192,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T15:44:39.941004Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.059795734s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T15:44:39.990505Z","caller":"traceutil/trace.go:171","msg":"trace[1737153841] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"1.019082374s","start":"2026-06-09T15:44:38.971394Z","end":"2026-06-09T15:44:39.990476Z","steps":["trace[1737153841] 'read index received' (duration: 969.734536ms)","trace[1737153841] 'applied index is now lower than readState.Index' (duration: 49.346638ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:44:39.99118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.978155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:44:39.991184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.441176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-09T15:44:39.991182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.591135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-09T15:44:39.991219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.707369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T15:44:39.991244Z","caller":"traceutil/trace.go:171","msg":"trace[492390021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"261.091298ms","start":"2026-06-09T15:44:39.730135Z","end":"2026-06-09T15:44:39.991227Z","steps":["trace[492390021] 'agreement among raft nodes before linearized reading' (duration: 260.896902ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:39.991248Z","caller":"traceutil/trace.go:171","msg":"trace[284650970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"903.55483ms","start":"2026-06-09T15:44:39.087679Z","end":"2026-06-09T15:44:39.991234Z","steps":["trace[284650970] 'agreement among raft nodes before linearized reading' (duration: 903.319762ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:39.99127Z","caller":"traceutil/trace.go:171","msg":"trace[1581272047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"725.720819ms","start":"2026-06-09T15:44:39.265533Z","end":"2026-06-09T15:44:39.991253Z","steps":["trace[1581272047] 'agreement among raft nodes before linearized reading' (duration: 725.462181ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:39.991271Z","caller":"traceutil/trace.go:171","msg":"trace[140417404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"725.786911ms","start":"2026-06-09T15:44:39.265466Z","end":"2026-06-09T15:44:39.991253Z","steps":["trace[140417404] 'agreement among raft nodes before linearized reading' (duration: 725.646697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:39.991293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:39.087653Z","time spent":"903.631072ms","remote":"127.0.0.1:44606","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-06-09T15:44:39.991311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:39.265515Z","time spent":"725.788712ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T15:44:39.991187Z","caller":"traceutil/trace.go:171","msg":"trace[1926844205] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.109493443s","start":"2026-06-09T15:44:38.881103Z","end":"2026-06-09T15:44:39.990597Z","steps":["trace[1926844205] 'process raft request' (duration: 1.060207857s)","trace[1926844205] 'compare' (duration: 48.77395ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:44:39.991311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:39.265449Z","time spent":"725.855364ms","remote":"127.0.0.1:44606","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-06-09T15:44:39.991402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:38.881081Z","time spent":"1.110266349s","remote":"127.0.0.1:44504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T15:44:39.99179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020399097s","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-06-09T15:44:39.991877Z","caller":"traceutil/trace.go:171","msg":"trace[1823859760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"1.02047965s","start":"2026-06-09T15:44:38.971344Z","end":"2026-06-09T15:44:39.991824Z","steps":["trace[1823859760] 'agreement among raft nodes before linearized reading' (duration: 1.01929002s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:39.991921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:38.971326Z","time spent":"1.020582803s","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T15:44:40.810106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.721752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:44:40.810211Z","caller":"traceutil/trace.go:171","msg":"trace[1554025467] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"814.904152ms","start":"2026-06-09T15:44:39.995289Z","end":"2026-06-09T15:44:40.810193Z","steps":["trace[1554025467] 'process raft request' (duration: 495.026285ms)","trace[1554025467] 'compare' (duration: 319.501135ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:44:40.810263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:39.995236Z","time spent":"815.006015ms","remote":"127.0.0.1:44390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:44:40.815851Z","caller":"traceutil/trace.go:171","msg":"trace[833005803] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"819.83168ms","start":"2026-06-09T15:44:39.996007Z","end":"2026-06-09T15:44:40.815839Z","steps":["trace[833005803] 'process raft request' (duration: 819.662805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:40.815918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:39.995992Z","time spent":"819.889592ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:44:40.815919Z","caller":"traceutil/trace.go:171","msg":"trace[1853819729] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"819.82587ms","start":"2026-06-09T15:44:39.996071Z","end":"2026-06-09T15:44:40.815897Z","steps":["trace[1853819729] 'process raft request' (duration: 819.758728ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:40.815946Z","caller":"traceutil/trace.go:171","msg":"trace[780449405] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"819.873822ms","start":"2026-06-09T15:44:39.996053Z","end":"2026-06-09T15:44:40.815926Z","steps":["trace[780449405] 'process raft request' (duration: 819.737067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:40.816043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:39.996041Z","time spent":"819.951594ms","remote":"127.0.0.1:44504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:44:40.816048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:39.996064Z","time spent":"819.913102ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:44:40.816055Z","caller":"traceutil/trace.go:171","msg":"trace[1946149144] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"819.137478ms","start":"2026-06-09T15:44:39.996903Z","end":"2026-06-09T15:44:40.816041Z","steps":["trace[1946149144] 'process raft request' (duration: 818.959543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:40.816187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:39.996894Z","time spent":"819.241051ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:44:40.816236Z","caller":"traceutil/trace.go:171","msg":"trace[919989413] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"818.732404ms","start":"2026-06-09T15:44:39.997493Z","end":"2026-06-09T15:44:40.816225Z","steps":["trace[919989413] 'process raft request' (duration: 818.491147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:40.816384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:39.997473Z","time spent":"818.850967ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:44:40.81649Z","caller":"traceutil/trace.go:171","msg":"trace[1028007575] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"818.116314ms","start":"2026-06-09T15:44:39.998356Z","end":"2026-06-09T15:44:40.816472Z","steps":["trace[1028007575] 'process raft request' (duration: 818.043042ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:40.816549Z","caller":"traceutil/trace.go:171","msg":"trace[2069029056] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"818.703563ms","start":"2026-06-09T15:44:39.997831Z","end":"2026-06-09T15:44:40.816535Z","steps":["trace[2069029056] 'process raft request' (duration: 818.317671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:40.816574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:39.998347Z","time spent":"818.186546ms","remote":"127.0.0.1:44574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:44:40.816625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:39.997823Z","time spent":"818.770105ms","remote":"127.0.0.1:44390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T15:44:42.794914Z","caller":"traceutil/trace.go:171","msg":"trace[1206035875] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"218.072971ms","start":"2026-06-09T15:44:42.576805Z","end":"2026-06-09T15:44:42.794878Z","steps":["trace[1206035875] 'process raft request' (duration: 217.848634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:56.947255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933266405530722500,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T15:44:57.120779Z","caller":"traceutil/trace.go:171","msg":"trace[1283077194] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"674.377604ms","start":"2026-06-09T15:44:56.446377Z","end":"2026-06-09T15:44:57.120754Z","steps":["trace[1283077194] 'read index received' (duration: 674.181369ms)","trace[1283077194] 'applied index is now lower than readState.Index' (duration: 195.275µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:44:57.121057Z","caller":"traceutil/trace.go:171","msg":"trace[298362142] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"843.776365ms","start":"2026-06-09T15:44:56.277266Z","end":"2026-06-09T15:44:57.121042Z","steps":["trace[298362142] 'process raft request' (duration: 843.321863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:57.121166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:56.27725Z","time spent":"843.844636ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:44:57.121311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.741682ms","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-06-09T15:44:57.1213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.497515ms","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-06-09T15:44:57.121339Z","caller":"traceutil/trace.go:171","msg":"trace[1776845038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:804; }","duration":"149.789554ms","start":"2026-06-09T15:44:56.971542Z","end":"2026-06-09T15:44:57.121332Z","steps":["trace[1776845038] 'agreement among raft nodes before linearized reading' (duration: 149.718122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:57.12133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.558957ms","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-06-09T15:44:57.121352Z","caller":"traceutil/trace.go:171","msg":"trace[1213349222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"149.587018ms","start":"2026-06-09T15:44:56.971752Z","end":"2026-06-09T15:44:57.12134Z","steps":["trace[1213349222] 'agreement among raft nodes before linearized reading' (duration: 149.446174ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:57.121404Z","caller":"traceutil/trace.go:171","msg":"trace[1312961622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"149.68184ms","start":"2026-06-09T15:44:56.971705Z","end":"2026-06-09T15:44:57.121386Z","steps":["trace[1312961622] 'agreement among raft nodes before linearized reading' (duration: 149.478455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:57.12143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.277126ms","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-06-09T15:44:57.121448Z","caller":"traceutil/trace.go:171","msg":"trace[2034591503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"204.313507ms","start":"2026-06-09T15:44:56.917129Z","end":"2026-06-09T15:44:57.121443Z","steps":["trace[2034591503] 'agreement among raft nodes before linearized reading' (duration: 204.263615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:57.121424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.945889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qkgvl\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-06-09T15:44:57.121619Z","caller":"traceutil/trace.go:171","msg":"trace[32001117] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qkgvl; range_end:; response_count:1; response_revision:804; }","duration":"675.262018ms","start":"2026-06-09T15:44:56.446334Z","end":"2026-06-09T15:44:57.121596Z","steps":["trace[32001117] 'agreement among raft nodes before linearized reading' (duration: 674.697273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:57.121761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:44:56.446313Z","time spent":"675.405272ms","remote":"127.0.0.1:44504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qkgvl\" "} {"level":"warn","ts":"2026-06-09T15:44:57.121498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.050053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T15:44:57.12191Z","caller":"traceutil/trace.go:171","msg":"trace[244008826] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:804; }","duration":"249.500626ms","start":"2026-06-09T15:44:56.872396Z","end":"2026-06-09T15:44:57.121896Z","steps":["trace[244008826] 'agreement among raft nodes before linearized reading' (duration: 248.767135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:44:57.408112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.876503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:44:57.408205Z","caller":"traceutil/trace.go:171","msg":"trace[750922414] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"282.698482ms","start":"2026-06-09T15:44:57.125487Z","end":"2026-06-09T15:44:57.408186Z","steps":["trace[750922414] 'process raft request' (duration: 67.681187ms)","trace[750922414] 'compare' (duration: 214.775829ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:44:57.411191Z","caller":"traceutil/trace.go:171","msg":"trace[1592504556] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"284.096172ms","start":"2026-06-09T15:44:57.127078Z","end":"2026-06-09T15:44:57.411175Z","steps":["trace[1592504556] 'process raft request' (duration: 284.02927ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:57.411266Z","caller":"traceutil/trace.go:171","msg":"trace[542080973] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"282.885017ms","start":"2026-06-09T15:44:57.128356Z","end":"2026-06-09T15:44:57.411241Z","steps":["trace[542080973] 'process raft request' (duration: 282.788195ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:57.411445Z","caller":"traceutil/trace.go:171","msg":"trace[1107840683] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"284.609445ms","start":"2026-06-09T15:44:57.126815Z","end":"2026-06-09T15:44:57.411424Z","steps":["trace[1107840683] 'process raft request' (duration: 284.163173ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:44:57.411638Z","caller":"traceutil/trace.go:171","msg":"trace[2073383853] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"284.691198ms","start":"2026-06-09T15:44:57.126929Z","end":"2026-06-09T15:44:57.41162Z","steps":["trace[2073383853] 'process raft request' (duration: 284.128452ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:45:25.438203Z","caller":"traceutil/trace.go:171","msg":"trace[1713509551] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"192.070824ms","start":"2026-06-09T15:45:25.24611Z","end":"2026-06-09T15:45:25.438181Z","steps":["trace[1713509551] 'process raft request' (duration: 191.606944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:25.880491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.353858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-06-09T15:45:25.880571Z","caller":"traceutil/trace.go:171","msg":"trace[1078536644] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:1204; }","duration":"212.484781ms","start":"2026-06-09T15:45:25.66807Z","end":"2026-06-09T15:45:25.880554Z","steps":["trace[1078536644] 'range keys from in-memory index tree' (duration: 212.178125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:25.880664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.266307ms","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-06-09T15:45:25.880808Z","caller":"traceutil/trace.go:171","msg":"trace[1997326663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1204; }","duration":"289.438701ms","start":"2026-06-09T15:45:25.591344Z","end":"2026-06-09T15:45:25.880783Z","steps":["trace[1997326663] 'range keys from in-memory index tree' (duration: 289.125353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:25.880916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.019308ms","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-06-09T15:45:25.880976Z","caller":"traceutil/trace.go:171","msg":"trace[1364767956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1204; }","duration":"300.117579ms","start":"2026-06-09T15:45:25.580843Z","end":"2026-06-09T15:45:25.88096Z","steps":["trace[1364767956] 'range keys from in-memory index tree' (duration: 299.867275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:25.881044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:45:25.580821Z","time spent":"300.209222ms","remote":"127.0.0.1:44606","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-06-09T15:45:25.881215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.138865ms","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-06-09T15:45:25.88131Z","caller":"traceutil/trace.go:171","msg":"trace[1645730563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1204; }","duration":"289.270227ms","start":"2026-06-09T15:45:25.592018Z","end":"2026-06-09T15:45:25.881288Z","steps":["trace[1645730563] 'range keys from in-memory index tree' (duration: 288.970631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:25.881504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.078094ms","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-06-09T15:45:25.881581Z","caller":"traceutil/trace.go:171","msg":"trace[1612645226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1204; }","duration":"291.053965ms","start":"2026-06-09T15:45:25.59051Z","end":"2026-06-09T15:45:25.881564Z","steps":["trace[1612645226] 'range keys from in-memory index tree' (duration: 289.849959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:42.605647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.145399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:45:42.605757Z","caller":"traceutil/trace.go:171","msg":"trace[1224699971] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1349; }","duration":"194.317502ms","start":"2026-06-09T15:45:42.411411Z","end":"2026-06-09T15:45:42.605729Z","steps":["trace[1224699971] 'count revisions from in-memory index tree' (duration: 194.053867ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:45:50.229662Z","caller":"traceutil/trace.go:171","msg":"trace[1179449231] linearizableReadLoop","detail":"{readStateIndex:1519; appliedIndex:1518; }","duration":"213.630298ms","start":"2026-06-09T15:45:50.016009Z","end":"2026-06-09T15:45:50.229639Z","steps":["trace[1179449231] 'read index received' (duration: 213.504946ms)","trace[1179449231] 'applied index is now lower than readState.Index' (duration: 113.402µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:45:50.229779Z","caller":"traceutil/trace.go:171","msg":"trace[249936706] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"224.863482ms","start":"2026-06-09T15:45:50.004904Z","end":"2026-06-09T15:45:50.229768Z","steps":["trace[249936706] 'process raft request' (duration: 224.554316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:50.230028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.990875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-09T15:45:50.230109Z","caller":"traceutil/trace.go:171","msg":"trace[2138943547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1473; }","duration":"214.103587ms","start":"2026-06-09T15:45:50.015985Z","end":"2026-06-09T15:45:50.230089Z","steps":["trace[2138943547] 'agreement among raft nodes before linearized reading' (duration: 213.876993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:50.423533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.519138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:45:50.423645Z","caller":"traceutil/trace.go:171","msg":"trace[653201755] linearizableReadLoop","detail":"{readStateIndex:1520; appliedIndex:1519; }","duration":"193.873883ms","start":"2026-06-09T15:45:50.229738Z","end":"2026-06-09T15:45:50.423612Z","steps":["trace[653201755] 'read index received' (duration: 79.196112ms)","trace[653201755] 'applied index is now lower than readState.Index' (duration: 114.676351ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:45:50.42381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.598254ms","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-06-09T15:45:50.423843Z","caller":"traceutil/trace.go:171","msg":"trace[1133955037] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1474; }","duration":"407.691255ms","start":"2026-06-09T15:45:50.016142Z","end":"2026-06-09T15:45:50.423833Z","steps":["trace[1133955037] 'agreement among raft nodes before linearized reading' (duration: 407.532502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:50.42388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:45:50.016123Z","time spent":"407.750306ms","remote":"127.0.0.1:44606","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-06-09T15:45:50.42384Z","caller":"traceutil/trace.go:171","msg":"trace[1535565201] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"244.62216ms","start":"2026-06-09T15:45:50.179176Z","end":"2026-06-09T15:45:50.423799Z","steps":["trace[1535565201] 'process raft request' (duration: 129.751064ms)","trace[1535565201] 'compare' (duration: 114.168462ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:45:50.424015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.627274ms","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-06-09T15:45:50.424067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.094625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T15:45:50.424149Z","caller":"traceutil/trace.go:171","msg":"trace[340831121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1474; }","duration":"407.203717ms","start":"2026-06-09T15:45:50.016928Z","end":"2026-06-09T15:45:50.424132Z","steps":["trace[340831121] 'agreement among raft nodes before linearized reading' (duration: 406.957272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:50.4242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:45:50.016921Z","time spent":"407.270038ms","remote":"127.0.0.1:44606","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":"info","ts":"2026-06-09T15:45:50.42412Z","caller":"traceutil/trace.go:171","msg":"trace[1755690413] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1474; }","duration":"221.803337ms","start":"2026-06-09T15:45:50.202298Z","end":"2026-06-09T15:45:50.424101Z","steps":["trace[1755690413] 'agreement among raft nodes before linearized reading' (duration: 221.530052ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:45:50.722136Z","caller":"traceutil/trace.go:171","msg":"trace[1582947582] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"460.146282ms","start":"2026-06-09T15:45:50.261959Z","end":"2026-06-09T15:45:50.722105Z","steps":["trace[1582947582] 'process raft request' (duration: 460.05375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:50.722454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:45:50.261937Z","time spent":"460.283565ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:45:50.722515Z","caller":"traceutil/trace.go:171","msg":"trace[1670569403] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"488.502654ms","start":"2026-06-09T15:45:50.233963Z","end":"2026-06-09T15:45:50.722465Z","steps":["trace[1670569403] 'process raft request' (duration: 487.753361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:50.722712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:45:50.233943Z","time spent":"488.660296ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:45:50.79273Z","caller":"traceutil/trace.go:171","msg":"trace[1838166361] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"363.537107ms","start":"2026-06-09T15:45:50.42916Z","end":"2026-06-09T15:45:50.792697Z","steps":["trace[1838166361] 'process raft request' (duration: 363.479366ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:45:50.792749Z","caller":"traceutil/trace.go:171","msg":"trace[455099401] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"364.2586ms","start":"2026-06-09T15:45:50.428464Z","end":"2026-06-09T15:45:50.792722Z","steps":["trace[455099401] 'process raft request' (duration: 364.047346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:50.792841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:45:50.429131Z","time spent":"363.658809ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:45:50.792846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:45:50.428428Z","time spent":"364.370302ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:45:51.006214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.522897ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-09T15:45:51.006326Z","caller":"traceutil/trace.go:171","msg":"trace[1350194617] linearizableReadLoop","detail":"{readStateIndex:1525; appliedIndex:1522; }","duration":"276.990153ms","start":"2026-06-09T15:45:50.729307Z","end":"2026-06-09T15:45:51.006297Z","steps":["trace[1350194617] 'read index received' (duration: 63.13576ms)","trace[1350194617] 'applied index is now lower than readState.Index' (duration: 213.853073ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:45:51.006438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.123255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:45:51.00651Z","caller":"traceutil/trace.go:171","msg":"trace[266345815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1478; }","duration":"277.265188ms","start":"2026-06-09T15:45:50.729225Z","end":"2026-06-09T15:45:51.00649Z","steps":["trace[266345815] 'agreement among raft nodes before linearized reading' (duration: 277.128045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:52.84005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.874038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:45:52.840104Z","caller":"traceutil/trace.go:171","msg":"trace[1958680216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1491; }","duration":"110.96497ms","start":"2026-06-09T15:45:52.729128Z","end":"2026-06-09T15:45:52.840093Z","steps":["trace[1958680216] 'agreement among raft nodes before linearized reading' (duration: 110.875088ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:45:52.84003Z","caller":"traceutil/trace.go:171","msg":"trace[2091031757] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"110.501891ms","start":"2026-06-09T15:45:52.729486Z","end":"2026-06-09T15:45:52.839988Z","steps":["trace[2091031757] 'process raft request' (duration: 78.676524ms)","trace[2091031757] 'compare' (duration: 31.543912ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:45:53.004344Z","caller":"traceutil/trace.go:171","msg":"trace[1454418636] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"159.659659ms","start":"2026-06-09T15:45:52.844659Z","end":"2026-06-09T15:45:53.004319Z","steps":["trace[1454418636] 'process raft request' (duration: 132.847221ms)","trace[1454418636] 'compare' (duration: 26.422512ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:45:53.004464Z","caller":"traceutil/trace.go:171","msg":"trace[1317267261] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"159.11347ms","start":"2026-06-09T15:45:52.845331Z","end":"2026-06-09T15:45:53.004444Z","steps":["trace[1317267261] 'process raft request' (duration: 158.903136ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:45:53.136077Z","caller":"traceutil/trace.go:171","msg":"trace[1087388823] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"124.54786ms","start":"2026-06-09T15:45:53.011504Z","end":"2026-06-09T15:45:53.136052Z","steps":["trace[1087388823] 'process raft request' (duration: 124.371427ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:45:53.339502Z","caller":"traceutil/trace.go:171","msg":"trace[1499403929] linearizableReadLoop","detail":"{readStateIndex:1544; appliedIndex:1543; }","duration":"134.003236ms","start":"2026-06-09T15:45:53.205475Z","end":"2026-06-09T15:45:53.339478Z","steps":["trace[1499403929] 'read index received' (duration: 45.984775ms)","trace[1499403929] 'applied index is now lower than readState.Index' (duration: 88.017071ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:45:53.339548Z","caller":"traceutil/trace.go:171","msg":"trace[1758551230] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"142.968947ms","start":"2026-06-09T15:45:53.196542Z","end":"2026-06-09T15:45:53.339511Z","steps":["trace[1758551230] 'process raft request' (duration: 54.888695ms)","trace[1758551230] 'compare' (duration: 87.926109ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:45:53.339744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.255731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1071"} {"level":"info","ts":"2026-06-09T15:45:53.339792Z","caller":"traceutil/trace.go:171","msg":"trace[812647212] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1497; }","duration":"134.354243ms","start":"2026-06-09T15:45:53.205424Z","end":"2026-06-09T15:45:53.339778Z","steps":["trace[812647212] 'agreement among raft nodes before linearized reading' (duration: 134.177939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:53.556164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.617889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ingress-nginx-admission\" ","response":"range_response_count:1 size:1224"} {"level":"info","ts":"2026-06-09T15:45:53.556347Z","caller":"traceutil/trace.go:171","msg":"trace[21345800] range","detail":"{range_begin:/registry/clusterrolebindings/ingress-nginx-admission; range_end:; response_count:1; response_revision:1499; }","duration":"103.844362ms","start":"2026-06-09T15:45:53.452486Z","end":"2026-06-09T15:45:53.55633Z","steps":["trace[21345800] 'range keys from in-memory index tree' (duration: 103.474926ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:53.991012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:45:53.991324Z","caller":"traceutil/trace.go:171","msg":"trace[1560719628] linearizableReadLoop","detail":"{readStateIndex:1548; appliedIndex:1547; }","duration":"333.789132ms","start":"2026-06-09T15:45:53.657515Z","end":"2026-06-09T15:45:53.991304Z","steps":["trace[1560719628] 'read index received' (duration: 133.02526ms)","trace[1560719628] 'applied index is now lower than readState.Index' (duration: 200.761592ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:45:53.991524Z","caller":"traceutil/trace.go:171","msg":"trace[417186910] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"340.059093ms","start":"2026-06-09T15:45:53.65144Z","end":"2026-06-09T15:45:53.9915Z","steps":["trace[417186910] 'process raft request' (duration: 139.149638ms)","trace[417186910] 'compare' (duration: 199.944248ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:45:53.991603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.084777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1104"} {"level":"info","ts":"2026-06-09T15:45:53.991645Z","caller":"traceutil/trace.go:171","msg":"trace[1353244402] range","detail":"{range_begin:/registry/roles/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1501; }","duration":"334.162918ms","start":"2026-06-09T15:45:53.657471Z","end":"2026-06-09T15:45:53.991634Z","steps":["trace[1353244402] 'agreement among raft nodes before linearized reading' (duration: 333.992305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:53.991685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:45:53.657455Z","time spent":"334.22252ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1127,"request content":"key:\"/registry/roles/ingress-nginx/ingress-nginx-admission\" "} {"level":"warn","ts":"2026-06-09T15:45:53.991669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:45:53.651421Z","time spent":"340.162595ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:45:53.993675Z","caller":"traceutil/trace.go:171","msg":"trace[2077178866] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"186.244345ms","start":"2026-06-09T15:45:53.80741Z","end":"2026-06-09T15:45:53.993654Z","steps":["trace[2077178866] 'process raft request' (duration: 186.038592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:53.993626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.501297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:45:53.993874Z","caller":"traceutil/trace.go:171","msg":"trace[451478778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1502; }","duration":"262.693061ms","start":"2026-06-09T15:45:53.731083Z","end":"2026-06-09T15:45:53.993776Z","steps":["trace[451478778] 'agreement among raft nodes before linearized reading' (duration: 262.476677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:45:53.993709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.690902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:45:53.993983Z","caller":"traceutil/trace.go:171","msg":"trace[979621880] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:0; response_revision:1502; }","duration":"227.000378ms","start":"2026-06-09T15:45:53.766972Z","end":"2026-06-09T15:45:53.993973Z","steps":["trace[979621880] 'agreement among raft nodes before linearized reading' (duration: 226.711533ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:45:54.146832Z","caller":"traceutil/trace.go:171","msg":"trace[1167848487] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"150.123189ms","start":"2026-06-09T15:45:53.996695Z","end":"2026-06-09T15:45:54.146818Z","steps":["trace[1167848487] 'process raft request' (duration: 149.857075ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:45:54.146766Z","caller":"traceutil/trace.go:171","msg":"trace[1580055632] linearizableReadLoop","detail":"{readStateIndex:1551; appliedIndex:1550; }","duration":"110.49946ms","start":"2026-06-09T15:45:54.036244Z","end":"2026-06-09T15:45:54.146743Z","steps":["trace[1580055632] 'read index received' (duration: 110.294507ms)","trace[1580055632] 'applied index is now lower than readState.Index' (duration: 203.723µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:45:54.147511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.244963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1230"} {"level":"info","ts":"2026-06-09T15:45:54.147629Z","caller":"traceutil/trace.go:171","msg":"trace[1725843008] range","detail":"{range_begin:/registry/rolebindings/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1504; }","duration":"111.366335ms","start":"2026-06-09T15:45:54.036241Z","end":"2026-06-09T15:45:54.147607Z","steps":["trace[1725843008] 'agreement among raft nodes before linearized reading' (duration: 111.09232ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:45:54.343474Z","caller":"traceutil/trace.go:171","msg":"trace[497135659] linearizableReadLoop","detail":"{readStateIndex:1553; appliedIndex:1552; }","duration":"136.182742ms","start":"2026-06-09T15:45:54.207261Z","end":"2026-06-09T15:45:54.343444Z","steps":["trace[497135659] 'read index received' (duration: 50.380758ms)","trace[497135659] 'applied index is now lower than readState.Index' (duration: 85.800524ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:45:54.343629Z","caller":"traceutil/trace.go:171","msg":"trace[1673862880] transaction","detail":"{read_only:false; response_revision:1506; number_of_response:1; }","duration":"191.948406ms","start":"2026-06-09T15:45:54.151631Z","end":"2026-06-09T15:45:54.34358Z","steps":["trace[1673862880] 'process raft request' (duration: 106.024861ms)","trace[1673862880] 'compare' (duration: 85.63584ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:45:54.343887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.6139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/ingress-nginx/ingress-nginx-admission-patch\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-09T15:45:54.344523Z","caller":"traceutil/trace.go:171","msg":"trace[47617989] range","detail":"{range_begin:/registry/jobs/ingress-nginx/ingress-nginx-admission-patch; range_end:; response_count:1; response_revision:1506; }","duration":"137.285131ms","start":"2026-06-09T15:45:54.207221Z","end":"2026-06-09T15:45:54.344506Z","steps":["trace[47617989] 'agreement among raft nodes before linearized reading' (duration: 136.461547ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:03.806891Z","caller":"traceutil/trace.go:171","msg":"trace[950306157] linearizableReadLoop","detail":"{readStateIndex:1800; appliedIndex:1799; }","duration":"124.05066ms","start":"2026-06-09T15:46:03.682817Z","end":"2026-06-09T15:46:03.806868Z","steps":["trace[950306157] 'read index received' (duration: 123.838517ms)","trace[950306157] 'applied index is now lower than readState.Index' (duration: 211.223µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:03.807224Z","caller":"traceutil/trace.go:171","msg":"trace[351420389] transaction","detail":"{read_only:false; response_revision:1733; number_of_response:1; }","duration":"164.903657ms","start":"2026-06-09T15:46:03.642298Z","end":"2026-06-09T15:46:03.807202Z","steps":["trace[351420389] 'process raft request' (duration: 164.409569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:03.807239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.370755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:46:03.807343Z","caller":"traceutil/trace.go:171","msg":"trace[445984041] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com; range_end:; response_count:0; response_revision:1733; }","duration":"124.548279ms","start":"2026-06-09T15:46:03.682777Z","end":"2026-06-09T15:46:03.807325Z","steps":["trace[445984041] 'agreement among raft nodes before linearized reading' (duration: 124.362306ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:07.572962Z","caller":"traceutil/trace.go:171","msg":"trace[1033602707] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"198.42399ms","start":"2026-06-09T15:46:07.374516Z","end":"2026-06-09T15:46:07.57294Z","steps":["trace[1033602707] 'process raft request' (duration: 198.285718ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:07.828356Z","caller":"traceutil/trace.go:171","msg":"trace[698875990] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"216.848562ms","start":"2026-06-09T15:46:07.611484Z","end":"2026-06-09T15:46:07.828333Z","steps":["trace[698875990] 'process raft request' (duration: 216.70892ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:08.026465Z","caller":"traceutil/trace.go:171","msg":"trace[1765404711] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"176.816683ms","start":"2026-06-09T15:46:07.849621Z","end":"2026-06-09T15:46:08.026438Z","steps":["trace[1765404711] 'process raft request' (duration: 150.584164ms)","trace[1765404711] 'compare' (duration: 25.840253ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:08.036327Z","caller":"traceutil/trace.go:171","msg":"trace[153567304] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"120.517718ms","start":"2026-06-09T15:46:07.915786Z","end":"2026-06-09T15:46:08.036304Z","steps":["trace[153567304] 'process raft request' (duration: 120.272164ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:08.304888Z","caller":"traceutil/trace.go:171","msg":"trace[1221141823] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"161.320517ms","start":"2026-06-09T15:46:08.143539Z","end":"2026-06-09T15:46:08.30486Z","steps":["trace[1221141823] 'process raft request' (duration: 139.785874ms)","trace[1221141823] 'compare' (duration: 21.425051ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:08.346243Z","caller":"traceutil/trace.go:171","msg":"trace[1520273853] linearizableReadLoop","detail":"{readStateIndex:1868; appliedIndex:1866; }","duration":"102.21403ms","start":"2026-06-09T15:46:08.24401Z","end":"2026-06-09T15:46:08.346224Z","steps":["trace[1520273853] 'read index received' (duration: 39.363133ms)","trace[1520273853] 'applied index is now lower than readState.Index' (duration: 62.850107ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:08.346371Z","caller":"traceutil/trace.go:171","msg":"trace[1831411530] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"201.152117ms","start":"2026-06-09T15:46:08.145174Z","end":"2026-06-09T15:46:08.346326Z","steps":["trace[1831411530] 'process raft request' (duration: 200.930253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:08.346812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.77712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:3 size:222187"} {"level":"info","ts":"2026-06-09T15:46:08.34688Z","caller":"traceutil/trace.go:171","msg":"trace[2114638292] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:3; response_revision:1796; }","duration":"102.881792ms","start":"2026-06-09T15:46:08.243983Z","end":"2026-06-09T15:46:08.346864Z","steps":["trace[2114638292] 'agreement among raft nodes before linearized reading' (duration: 102.349023ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:10.264215Z","caller":"traceutil/trace.go:171","msg":"trace[1181951639] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"113.68677ms","start":"2026-06-09T15:46:10.150503Z","end":"2026-06-09T15:46:10.264189Z","steps":["trace[1181951639] 'process raft request' (duration: 113.554348ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:16.971201Z","caller":"traceutil/trace.go:171","msg":"trace[617528852] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"130.085315ms","start":"2026-06-09T15:46:16.841093Z","end":"2026-06-09T15:46:16.971179Z","steps":["trace[617528852] 'process raft request' (duration: 98.978576ms)","trace[617528852] 'compare' (duration: 30.950387ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:16.971818Z","caller":"traceutil/trace.go:171","msg":"trace[674244208] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"111.502262ms","start":"2026-06-09T15:46:16.860303Z","end":"2026-06-09T15:46:16.971805Z","steps":["trace[674244208] 'process raft request' (duration: 110.824062ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:17.962049Z","caller":"traceutil/trace.go:171","msg":"trace[1504824822] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"120.897562ms","start":"2026-06-09T15:46:17.841118Z","end":"2026-06-09T15:46:17.962016Z","steps":["trace[1504824822] 'process raft request' (duration: 120.579457ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:17.965357Z","caller":"traceutil/trace.go:171","msg":"trace[428945328] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1928; }","duration":"107.699865ms","start":"2026-06-09T15:46:17.857614Z","end":"2026-06-09T15:46:17.965314Z","steps":["trace[428945328] 'read index received' (duration: 104.066729ms)","trace[428945328] 'applied index is now lower than readState.Index' (duration: 3.630566ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:17.965436Z","caller":"traceutil/trace.go:171","msg":"trace[620448219] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"123.92597ms","start":"2026-06-09T15:46:17.84149Z","end":"2026-06-09T15:46:17.965416Z","steps":["trace[620448219] 'process raft request' (duration: 123.618465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:17.965757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.113681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:46:17.965847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.9757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/openstack/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/openstack0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:46:17.966009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.130412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:46:17.966384Z","caller":"traceutil/trace.go:171","msg":"trace[1631410320] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:1856; }","duration":"108.735982ms","start":"2026-06-09T15:46:17.857595Z","end":"2026-06-09T15:46:17.966331Z","steps":["trace[1631410320] 'agreement among raft nodes before linearized reading' (duration: 108.099092ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:17.965982Z","caller":"traceutil/trace.go:171","msg":"trace[2097329228] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:1856; }","duration":"108.369506ms","start":"2026-06-09T15:46:17.857589Z","end":"2026-06-09T15:46:17.965959Z","steps":["trace[2097329228] 'agreement among raft nodes before linearized reading' (duration: 108.044081ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:17.966144Z","caller":"traceutil/trace.go:171","msg":"trace[2072916321] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/openstack/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores/openstack0; response_count:0; response_revision:1856; }","duration":"108.187633ms","start":"2026-06-09T15:46:17.857826Z","end":"2026-06-09T15:46:17.966013Z","steps":["trace[2072916321] 'agreement among raft nodes before linearized reading' (duration: 107.948199ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:20.449425Z","caller":"traceutil/trace.go:171","msg":"trace[77326885] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"120.865326ms","start":"2026-06-09T15:46:20.328534Z","end":"2026-06-09T15:46:20.449399Z","steps":["trace[77326885] 'process raft request' (duration: 120.673013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.21807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.573589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48da5fe6-c1dc-44cb-a376-0bfe1b57af40\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-06-09T15:46:21.218143Z","caller":"traceutil/trace.go:171","msg":"trace[33083394] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48da5fe6-c1dc-44cb-a376-0bfe1b57af40; range_end:; response_count:1; response_revision:1926; }","duration":"622.679731ms","start":"2026-06-09T15:46:20.595445Z","end":"2026-06-09T15:46:21.218124Z","steps":["trace[33083394] 'range keys from in-memory index tree' (duration: 622.398837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.218182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:20.595425Z","time spent":"622.747372ms","remote":"127.0.0.1:44504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48da5fe6-c1dc-44cb-a376-0bfe1b57af40\" "} {"level":"warn","ts":"2026-06-09T15:46:21.2182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.807472ms","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-06-09T15:46:21.218388Z","caller":"traceutil/trace.go:171","msg":"trace[610682046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1926; }","duration":"654.277728ms","start":"2026-06-09T15:46:20.564043Z","end":"2026-06-09T15:46:21.218321Z","steps":["trace[610682046] 'range keys from in-memory index tree' (duration: 653.630109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.218455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:20.564017Z","time spent":"654.421411ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T15:46:21.217916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.300884ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:46:21.218814Z","caller":"traceutil/trace.go:171","msg":"trace[1968229941] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1926; }","duration":"129.207637ms","start":"2026-06-09T15:46:21.089584Z","end":"2026-06-09T15:46:21.218791Z","steps":["trace[1968229941] 'range keys from in-memory index tree' (duration: 128.285192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.325301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.45649ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-09T15:46:21.325404Z","caller":"traceutil/trace.go:171","msg":"trace[101158086] linearizableReadLoop","detail":"{readStateIndex:2001; appliedIndex:2000; }","duration":"512.168028ms","start":"2026-06-09T15:46:20.813216Z","end":"2026-06-09T15:46:21.325384Z","steps":["trace[101158086] 'read index received' (duration: 382.709787ms)","trace[101158086] 'applied index is now lower than readState.Index' (duration: 129.456451ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:46:21.325688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.454692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48da5fe6-c1dc-44cb-a376-0bfe1b57af40\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-06-09T15:46:21.32581Z","caller":"traceutil/trace.go:171","msg":"trace[2011692033] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48da5fe6-c1dc-44cb-a376-0bfe1b57af40; range_end:; response_count:1; response_revision:1926; }","duration":"512.624074ms","start":"2026-06-09T15:46:20.813159Z","end":"2026-06-09T15:46:21.325783Z","steps":["trace[2011692033] 'agreement among raft nodes before linearized reading' (duration: 512.289619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.325877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:20.813113Z","time spent":"512.748386ms","remote":"127.0.0.1:44504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48da5fe6-c1dc-44cb-a376-0bfe1b57af40\" "} {"level":"info","ts":"2026-06-09T15:46:21.49192Z","caller":"traceutil/trace.go:171","msg":"trace[1643456748] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"510.799314ms","start":"2026-06-09T15:46:20.981084Z","end":"2026-06-09T15:46:21.491883Z","steps":["trace[1643456748] 'process raft request' (duration: 510.647782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.492167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:20.981052Z","time spent":"510.930257ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:46:21.91318Z","caller":"traceutil/trace.go:171","msg":"trace[145866599] linearizableReadLoop","detail":"{readStateIndex:2005; appliedIndex:2001; }","duration":"587.725276ms","start":"2026-06-09T15:46:21.325431Z","end":"2026-06-09T15:46:21.913157Z","steps":["trace[145866599] 'read index received' (duration: 166.358768ms)","trace[145866599] 'applied index is now lower than readState.Index' (duration: 421.365508ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:21.913242Z","caller":"traceutil/trace.go:171","msg":"trace[830440593] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"628.681506ms","start":"2026-06-09T15:46:21.284533Z","end":"2026-06-09T15:46:21.913215Z","steps":["trace[830440593] 'process raft request' (duration: 628.390131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.913302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.189126ms","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":"info","ts":"2026-06-09T15:46:21.913327Z","caller":"traceutil/trace.go:171","msg":"trace[99761042] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"579.453158ms","start":"2026-06-09T15:46:21.333853Z","end":"2026-06-09T15:46:21.913306Z","steps":["trace[99761042] 'process raft request' (duration: 579.209844ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:21.913357Z","caller":"traceutil/trace.go:171","msg":"trace[1794454340] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1932; }","duration":"754.285787ms","start":"2026-06-09T15:46:21.159057Z","end":"2026-06-09T15:46:21.913343Z","steps":["trace[1794454340] 'agreement among raft nodes before linearized reading' (duration: 754.136834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.913389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:21.159043Z","time spent":"754.340377ms","remote":"127.0.0.1:44418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":25,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-06-09T15:46:21.913429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.618946ms","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-06-09T15:46:21.913493Z","caller":"traceutil/trace.go:171","msg":"trace[493972362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1932; }","duration":"924.714968ms","start":"2026-06-09T15:46:20.98876Z","end":"2026-06-09T15:46:21.913475Z","steps":["trace[493972362] 'agreement among raft nodes before linearized reading' (duration: 924.543765ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:21.913545Z","caller":"traceutil/trace.go:171","msg":"trace[1326211811] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"550.125587ms","start":"2026-06-09T15:46:21.36341Z","end":"2026-06-09T15:46:21.913536Z","steps":["trace[1326211811] 'process raft request' (duration: 549.704841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.913544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:20.988747Z","time spent":"924.784519ms","remote":"127.0.0.1:44606","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-06-09T15:46:21.913528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:21.333824Z","time spent":"579.555169ms","remote":"127.0.0.1:44504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3730,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:46:21.913567Z","caller":"traceutil/trace.go:171","msg":"trace[616223764] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"688.582936ms","start":"2026-06-09T15:46:21.224972Z","end":"2026-06-09T15:46:21.913554Z","steps":["trace[616223764] 'process raft request' (duration: 687.775054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.913608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:21.363383Z","time spent":"550.182118ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:46:21.913626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:21.224955Z","time spent":"688.640917ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:46:21.913743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.349954ms","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-06-09T15:46:21.913784Z","caller":"traceutil/trace.go:171","msg":"trace[1460990841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1932; }","duration":"742.416724ms","start":"2026-06-09T15:46:21.171357Z","end":"2026-06-09T15:46:21.913774Z","steps":["trace[1460990841] 'agreement among raft nodes before linearized reading' (duration: 742.307222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.913815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:21.171335Z","time spent":"742.470696ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T15:46:21.913378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:21.284496Z","time spent":"628.805057ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:46:21.913855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.257032ms","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-06-09T15:46:21.913893Z","caller":"traceutil/trace.go:171","msg":"trace[973358490] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1932; }","duration":"768.293753ms","start":"2026-06-09T15:46:21.145587Z","end":"2026-06-09T15:46:21.913881Z","steps":["trace[973358490] 'agreement among raft nodes before linearized reading' (duration: 768.176601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.913786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.162253ms","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-06-09T15:46:21.91401Z","caller":"traceutil/trace.go:171","msg":"trace[163684390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1932; }","duration":"908.407827ms","start":"2026-06-09T15:46:21.005584Z","end":"2026-06-09T15:46:21.913992Z","steps":["trace[163684390] 'agreement among raft nodes before linearized reading' (duration: 907.685865ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.913923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:21.145515Z","time spent":"768.401924ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-09T15:46:21.914134Z","caller":"traceutil/trace.go:171","msg":"trace[1717680498] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"589.970161ms","start":"2026-06-09T15:46:21.324154Z","end":"2026-06-09T15:46:21.914124Z","steps":["trace[1717680498] 'process raft request' (duration: 588.832133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.914212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:21.324118Z","time spent":"590.045462ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:46:21.914148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:21.005571Z","time spent":"908.563379ms","remote":"127.0.0.1:44606","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-06-09T15:46:21.914077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.185151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:46:21.914031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.945181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:46:21.914353Z","caller":"traceutil/trace.go:171","msg":"trace[1476586670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1932; }","duration":"182.497126ms","start":"2026-06-09T15:46:21.731845Z","end":"2026-06-09T15:46:21.914342Z","steps":["trace[1476586670] 'agreement among raft nodes before linearized reading' (duration: 182.216281ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:21.914402Z","caller":"traceutil/trace.go:171","msg":"trace[1262744481] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1932; }","duration":"980.339517ms","start":"2026-06-09T15:46:20.934028Z","end":"2026-06-09T15:46:21.914368Z","steps":["trace[1262744481] 'agreement among raft nodes before linearized reading' (duration: 979.953521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:21.914459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:20.933998Z","time spent":"980.432669ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-09T15:46:34.074369Z","caller":"traceutil/trace.go:171","msg":"trace[1628250637] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"122.333927ms","start":"2026-06-09T15:46:33.951962Z","end":"2026-06-09T15:46:34.074296Z","steps":["trace[1628250637] 'process raft request' (duration: 122.193574ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:34.079665Z","caller":"traceutil/trace.go:171","msg":"trace[489252775] linearizableReadLoop","detail":"{readStateIndex:2127; appliedIndex:2126; }","duration":"115.779791ms","start":"2026-06-09T15:46:33.963868Z","end":"2026-06-09T15:46:34.079648Z","steps":["trace[489252775] 'read index received' (duration: 110.453314ms)","trace[489252775] 'applied index is now lower than readState.Index' (duration: 5.325417ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:34.079887Z","caller":"traceutil/trace.go:171","msg":"trace[672843753] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"120.526111ms","start":"2026-06-09T15:46:33.959347Z","end":"2026-06-09T15:46:34.079873Z","steps":["trace[672843753] 'process raft request' (duration: 120.183055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:34.080244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.35888ms","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-06-09T15:46:34.080315Z","caller":"traceutil/trace.go:171","msg":"trace[2040776981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2050; }","duration":"116.480411ms","start":"2026-06-09T15:46:33.963823Z","end":"2026-06-09T15:46:34.080303Z","steps":["trace[2040776981] 'agreement among raft nodes before linearized reading' (duration: 116.312199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:34.080503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.633804ms","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-06-09T15:46:34.080538Z","caller":"traceutil/trace.go:171","msg":"trace[2065090719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2050; }","duration":"116.702324ms","start":"2026-06-09T15:46:33.963825Z","end":"2026-06-09T15:46:34.080528Z","steps":["trace[2065090719] 'agreement among raft nodes before linearized reading' (duration: 116.617223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:34.080728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.158374ms","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-06-09T15:46:34.08076Z","caller":"traceutil/trace.go:171","msg":"trace[1290590610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2050; }","duration":"107.216415ms","start":"2026-06-09T15:46:33.973536Z","end":"2026-06-09T15:46:34.080752Z","steps":["trace[1290590610] 'agreement among raft nodes before linearized reading' (duration: 107.130733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:34.081231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.212491ms","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-06-09T15:46:34.081267Z","caller":"traceutil/trace.go:171","msg":"trace[1609315156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2050; }","duration":"104.280833ms","start":"2026-06-09T15:46:33.976977Z","end":"2026-06-09T15:46:34.081258Z","steps":["trace[1609315156] 'agreement among raft nodes before linearized reading' (duration: 104.195702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:35.230964Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933266405530724915,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T15:46:35.45161Z","caller":"traceutil/trace.go:171","msg":"trace[202307368] linearizableReadLoop","detail":"{readStateIndex:2133; appliedIndex:2132; }","duration":"721.014539ms","start":"2026-06-09T15:46:34.730574Z","end":"2026-06-09T15:46:35.451588Z","steps":["trace[202307368] 'read index received' (duration: 720.834567ms)","trace[202307368] 'applied index is now lower than readState.Index' (duration: 178.952µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:35.451703Z","caller":"traceutil/trace.go:171","msg":"trace[34371130] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"936.050586ms","start":"2026-06-09T15:46:34.515603Z","end":"2026-06-09T15:46:35.451654Z","steps":["trace[34371130] 'process raft request' (duration: 935.800343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:35.451772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.187173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:46:35.451798Z","caller":"traceutil/trace.go:171","msg":"trace[160361135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2055; }","duration":"721.267713ms","start":"2026-06-09T15:46:34.730521Z","end":"2026-06-09T15:46:35.451789Z","steps":["trace[160361135] 'agreement among raft nodes before linearized reading' (duration: 721.207632ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:35.451831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:34.730503Z","time spent":"721.313045ms","remote":"127.0.0.1:44290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T15:46:35.451853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:34.515589Z","time spent":"936.181388ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:46:35.452081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.723662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T15:46:35.452176Z","caller":"traceutil/trace.go:171","msg":"trace[583195859] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2055; }","duration":"325.853094ms","start":"2026-06-09T15:46:35.126296Z","end":"2026-06-09T15:46:35.452149Z","steps":["trace[583195859] 'agreement among raft nodes before linearized reading' (duration: 325.632101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:35.452228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:35.12629Z","time spent":"325.926815ms","remote":"127.0.0.1:44648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":4,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-06-09T15:46:35.45232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.751129ms","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-06-09T15:46:35.452406Z","caller":"traceutil/trace.go:171","msg":"trace[1718723020] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2055; }","duration":"365.212215ms","start":"2026-06-09T15:46:35.087158Z","end":"2026-06-09T15:46:35.452371Z","steps":["trace[1718723020] 'agreement among raft nodes before linearized reading' (duration: 364.671138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:35.45245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:35.087144Z","time spent":"365.293866ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T15:46:45.698909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.769148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:46:45.699034Z","caller":"traceutil/trace.go:171","msg":"trace[1521629245] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"124.951382ms","start":"2026-06-09T15:46:45.574054Z","end":"2026-06-09T15:46:45.699005Z","steps":["trace[1521629245] 'process raft request' (duration: 18.013392ms)","trace[1521629245] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; req_size:531; } (duration: 106.654477ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:49.850635Z","caller":"traceutil/trace.go:171","msg":"trace[1666118790] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"119.582845ms","start":"2026-06-09T15:46:49.731028Z","end":"2026-06-09T15:46:49.850611Z","steps":["trace[1666118790] 'read index received' (duration: 119.475544ms)","trace[1666118790] 'applied index is now lower than readState.Index' (duration: 106.381µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:49.850735Z","caller":"traceutil/trace.go:171","msg":"trace[704080562] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"138.733041ms","start":"2026-06-09T15:46:49.711967Z","end":"2026-06-09T15:46:49.8507Z","steps":["trace[704080562] 'process raft request' (duration: 138.501088ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:49.850774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.720828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:46:49.851021Z","caller":"traceutil/trace.go:171","msg":"trace[1926867668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2142; }","duration":"120.048303ms","start":"2026-06-09T15:46:49.730958Z","end":"2026-06-09T15:46:49.851006Z","steps":["trace[1926867668] 'agreement among raft nodes before linearized reading' (duration: 119.742818ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:52.045032Z","caller":"traceutil/trace.go:171","msg":"trace[1631230018] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"188.594185ms","start":"2026-06-09T15:46:51.856412Z","end":"2026-06-09T15:46:52.045007Z","steps":["trace[1631230018] 'process raft request' (duration: 188.357551ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:52.54615Z","caller":"traceutil/trace.go:171","msg":"trace[494751568] linearizableReadLoop","detail":"{readStateIndex:2236; appliedIndex:2233; }","duration":"268.569095ms","start":"2026-06-09T15:46:52.27756Z","end":"2026-06-09T15:46:52.546129Z","steps":["trace[494751568] 'read index received' (duration: 10.934769ms)","trace[494751568] 'applied index is now lower than readState.Index' (duration: 257.633466ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:52.546412Z","caller":"traceutil/trace.go:171","msg":"trace[1445171953] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"318.614033ms","start":"2026-06-09T15:46:52.227779Z","end":"2026-06-09T15:46:52.546393Z","steps":["trace[1445171953] 'process raft request' (duration: 318.292848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:52.546604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:52.227769Z","time spent":"318.728774ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:46:52.546886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.327026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T15:46:52.547277Z","caller":"traceutil/trace.go:171","msg":"trace[1184844146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2154; }","duration":"269.753222ms","start":"2026-06-09T15:46:52.277503Z","end":"2026-06-09T15:46:52.547256Z","steps":["trace[1184844146] 'agreement among raft nodes before linearized reading' (duration: 269.269175ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:52.546874Z","caller":"traceutil/trace.go:171","msg":"trace[939194754] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"319.305122ms","start":"2026-06-09T15:46:52.227537Z","end":"2026-06-09T15:46:52.546842Z","steps":["trace[939194754] 'process raft request' (duration: 310.201037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:52.548088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:52.227506Z","time spent":"320.449038ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:46:52.570331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.332485ms","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-06-09T15:46:52.570399Z","caller":"traceutil/trace.go:171","msg":"trace[253724730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2155; }","duration":"242.448167ms","start":"2026-06-09T15:46:52.327936Z","end":"2026-06-09T15:46:52.570384Z","steps":["trace[253724730] 'agreement among raft nodes before linearized reading' (duration: 242.267024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:52.570649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.156777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-09T15:46:52.570752Z","caller":"traceutil/trace.go:171","msg":"trace[1404811688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2155; }","duration":"243.307589ms","start":"2026-06-09T15:46:52.327424Z","end":"2026-06-09T15:46:52.570732Z","steps":["trace[1404811688] 'agreement among raft nodes before linearized reading' (duration: 242.908644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:52.570938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.332309ms","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-06-09T15:46:52.571041Z","caller":"traceutil/trace.go:171","msg":"trace[355249548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2155; }","duration":"243.947897ms","start":"2026-06-09T15:46:52.327073Z","end":"2026-06-09T15:46:52.571021Z","steps":["trace[355249548] 'agreement among raft nodes before linearized reading' (duration: 243.206847ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:53.23133Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933266405530725151,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T15:46:54.227112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:53.679089Z","time spent":"548.016149ms","remote":"127.0.0.1:44516","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-09T15:46:54.228767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.522289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:46:54.228972Z","caller":"traceutil/trace.go:171","msg":"trace[394783156] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2238; }","duration":"1.498162083s","start":"2026-06-09T15:46:52.730794Z","end":"2026-06-09T15:46:54.228956Z","steps":["trace[394783156] 'read index received' (duration: 875.324539ms)","trace[394783156] 'applied index is now lower than readState.Index' (duration: 622.836564ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:54.229226Z","caller":"traceutil/trace.go:171","msg":"trace[352851064] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"1.653834509s","start":"2026-06-09T15:46:52.575375Z","end":"2026-06-09T15:46:54.229209Z","steps":["trace[352851064] 'process raft request' (duration: 1.653540235s)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:54.229256Z","caller":"traceutil/trace.go:171","msg":"trace[1462853188] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"1.654768611s","start":"2026-06-09T15:46:52.574465Z","end":"2026-06-09T15:46:54.229234Z","steps":["trace[1462853188] 'process raft request' (duration: 1.031695885s)","trace[1462853188] 'compare' (duration: 622.408097ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:46:54.229311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:52.575356Z","time spent":"1.65391015s","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:46:54.229319Z","caller":"traceutil/trace.go:171","msg":"trace[1057936243] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"1.65391145s","start":"2026-06-09T15:46:52.575374Z","end":"2026-06-09T15:46:54.229286Z","steps":["trace[1057936243] 'process raft request' (duration: 1.653480284s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.229477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:52.575357Z","time spent":"1.654034271s","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:46:54.229344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:52.574438Z","time spent":"1.654860812s","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:46:54.229265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498465267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:46:54.229735Z","caller":"traceutil/trace.go:171","msg":"trace[1813594246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2159; }","duration":"1.498924583s","start":"2026-06-09T15:46:52.730755Z","end":"2026-06-09T15:46:54.22968Z","steps":["trace[1813594246] 'agreement among raft nodes before linearized reading' (duration: 1.498471517s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.229818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:52.730736Z","time spent":"1.499051405s","remote":"127.0.0.1:44290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T15:46:54.510273Z","caller":"traceutil/trace.go:171","msg":"trace[1590606693] linearizableReadLoop","detail":"{readStateIndex:2242; appliedIndex:2241; }","duration":"281.246728ms","start":"2026-06-09T15:46:54.229004Z","end":"2026-06-09T15:46:54.510251Z","steps":["trace[1590606693] 'read index received' (duration: 281.152917ms)","trace[1590606693] 'applied index is now lower than readState.Index' (duration: 92.681µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:46:54.510438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.009329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:114206"} {"level":"info","ts":"2026-06-09T15:46:54.510493Z","caller":"traceutil/trace.go:171","msg":"trace[256962798] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2160; }","duration":"521.124711ms","start":"2026-06-09T15:46:53.989355Z","end":"2026-06-09T15:46:54.51048Z","steps":["trace[256962798] 'agreement among raft nodes before linearized reading' (duration: 520.926878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.510474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.720558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-06-09T15:46:54.510528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:53.989342Z","time spent":"521.176171ms","remote":"127.0.0.1:44378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":114229,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-06-09T15:46:54.510539Z","caller":"traceutil/trace.go:171","msg":"trace[1621976336] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2160; }","duration":"526.835669ms","start":"2026-06-09T15:46:53.983692Z","end":"2026-06-09T15:46:54.510528Z","steps":["trace[1621976336] 'agreement among raft nodes before linearized reading' (duration: 526.679376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.510572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:53.983665Z","time spent":"526.89638ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-06-09T15:46:54.510633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.374259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T15:46:54.510713Z","caller":"traceutil/trace.go:171","msg":"trace[1438195054] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2160; }","duration":"418.549561ms","start":"2026-06-09T15:46:54.092148Z","end":"2026-06-09T15:46:54.510698Z","steps":["trace[1438195054] 'agreement among raft nodes before linearized reading' (duration: 418.393449ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:54.510696Z","caller":"traceutil/trace.go:171","msg":"trace[1973824553] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"459.707402ms","start":"2026-06-09T15:46:54.05095Z","end":"2026-06-09T15:46:54.510657Z","steps":["trace[1973824553] 'process raft request' (duration: 459.156085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.51073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.697104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-09T15:46:54.510752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:54.092127Z","time spent":"418.615312ms","remote":"127.0.0.1:44472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-06-09T15:46:54.5108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294553074s","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":"info","ts":"2026-06-09T15:46:54.510801Z","caller":"traceutil/trace.go:171","msg":"trace[1371727216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2160; }","duration":"954.787875ms","start":"2026-06-09T15:46:53.555996Z","end":"2026-06-09T15:46:54.510783Z","steps":["trace[1371727216] 'agreement among raft nodes before linearized reading' (duration: 954.666133ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:54.510836Z","caller":"traceutil/trace.go:171","msg":"trace[1402968561] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2160; }","duration":"1.294632325s","start":"2026-06-09T15:46:53.216194Z","end":"2026-06-09T15:46:54.510826Z","steps":["trace[1402968561] 'agreement among raft nodes before linearized reading' (duration: 1.294549713s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.510847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:53.555979Z","time spent":"954.859886ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T15:46:54.510866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:53.216173Z","time spent":"1.294685255s","remote":"127.0.0.1:44860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":36,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-06-09T15:46:54.510867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:54.050925Z","time spent":"459.847614ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:46:54.510884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.185565ms","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-06-09T15:46:54.510963Z","caller":"traceutil/trace.go:171","msg":"trace[779687198] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2160; }","duration":"883.338307ms","start":"2026-06-09T15:46:53.627602Z","end":"2026-06-09T15:46:54.51094Z","steps":["trace[779687198] 'agreement among raft nodes before linearized reading' (duration: 883.057173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.511012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:53.627583Z","time spent":"883.419109ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T15:46:54.511054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.61411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b7744307e69e11\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-09T15:46:54.511106Z","caller":"traceutil/trace.go:171","msg":"trace[399942984] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b7744307e69e11; range_end:; response_count:1; response_revision:2160; }","duration":"614.679841ms","start":"2026-06-09T15:46:53.896396Z","end":"2026-06-09T15:46:54.511076Z","steps":["trace[399942984] 'agreement among raft nodes before linearized reading' (duration: 614.59236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.511135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:53.896374Z","time spent":"614.754403ms","remote":"127.0.0.1:44390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b7744307e69e11\" "} {"level":"warn","ts":"2026-06-09T15:46:54.511346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.377324ms","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":"info","ts":"2026-06-09T15:46:54.51143Z","caller":"traceutil/trace.go:171","msg":"trace[581010376] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2160; }","duration":"360.178495ms","start":"2026-06-09T15:46:54.151215Z","end":"2026-06-09T15:46:54.511393Z","steps":["trace[581010376] 'agreement among raft nodes before linearized reading' (duration: 359.241363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.511507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:54.151189Z","time spent":"360.287486ms","remote":"127.0.0.1:44520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-06-09T15:46:54.66221Z","caller":"traceutil/trace.go:171","msg":"trace[709212453] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2242; }","duration":"151.840322ms","start":"2026-06-09T15:46:54.510346Z","end":"2026-06-09T15:46:54.662186Z","steps":["trace[709212453] 'read index received' (duration: 129.541337ms)","trace[709212453] 'applied index is now lower than readState.Index' (duration: 22.297675ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:46:54.66233Z","caller":"traceutil/trace.go:171","msg":"trace[1978776437] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"155.782926ms","start":"2026-06-09T15:46:54.506536Z","end":"2026-06-09T15:46:54.662319Z","steps":["trace[1978776437] 'process raft request' (duration: 155.599874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.662473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.644559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:46:54.662549Z","caller":"traceutil/trace.go:171","msg":"trace[512542049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2162; }","duration":"431.75936ms","start":"2026-06-09T15:46:54.230772Z","end":"2026-06-09T15:46:54.662532Z","steps":["trace[512542049] 'agreement among raft nodes before linearized reading' (duration: 431.635798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.662587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:54.230733Z","time spent":"431.846442ms","remote":"127.0.0.1:44298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T15:46:54.662237Z","caller":"traceutil/trace.go:171","msg":"trace[1931786494] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"368.950524ms","start":"2026-06-09T15:46:54.293253Z","end":"2026-06-09T15:46:54.662204Z","steps":["trace[1931786494] 'process raft request' (duration: 346.753481ms)","trace[1931786494] 'compare' (duration: 22.01962ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:46:54.663114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.443711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12384"} {"level":"info","ts":"2026-06-09T15:46:54.663226Z","caller":"traceutil/trace.go:171","msg":"trace[687231852] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2162; }","duration":"207.664443ms","start":"2026-06-09T15:46:54.455544Z","end":"2026-06-09T15:46:54.663208Z","steps":["trace[687231852] 'agreement among raft nodes before linearized reading' (duration: 206.836352ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.663123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:46:54.293225Z","time spent":"369.756425ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:46:54.670326Z","caller":"traceutil/trace.go:171","msg":"trace[1556544761] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"156.783009ms","start":"2026-06-09T15:46:54.513511Z","end":"2026-06-09T15:46:54.670294Z","steps":["trace[1556544761] 'process raft request' (duration: 156.498776ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:54.670586Z","caller":"traceutil/trace.go:171","msg":"trace[1145269795] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"118.858152ms","start":"2026-06-09T15:46:54.551714Z","end":"2026-06-09T15:46:54.670572Z","steps":["trace[1145269795] 'process raft request' (duration: 118.761651ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:54.670803Z","caller":"traceutil/trace.go:171","msg":"trace[1263353377] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"154.822352ms","start":"2026-06-09T15:46:54.515971Z","end":"2026-06-09T15:46:54.670794Z","steps":["trace[1263353377] 'process raft request' (duration: 154.242404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.670889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.963495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25777"} {"level":"info","ts":"2026-06-09T15:46:54.670952Z","caller":"traceutil/trace.go:171","msg":"trace[1973150014] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2166; }","duration":"158.059306ms","start":"2026-06-09T15:46:54.512878Z","end":"2026-06-09T15:46:54.670937Z","steps":["trace[1973150014] 'agreement among raft nodes before linearized reading' (duration: 157.758312ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:46:54.671039Z","caller":"traceutil/trace.go:171","msg":"trace[225736573] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"117.658555ms","start":"2026-06-09T15:46:54.55337Z","end":"2026-06-09T15:46:54.671029Z","steps":["trace[225736573] 'process raft request' (duration: 117.171819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:46:54.671443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.272759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25777"} {"level":"info","ts":"2026-06-09T15:46:54.671485Z","caller":"traceutil/trace.go:171","msg":"trace[1026623551] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2166; }","duration":"158.39437ms","start":"2026-06-09T15:46:54.513082Z","end":"2026-06-09T15:46:54.671476Z","steps":["trace[1026623551] 'agreement among raft nodes before linearized reading' (duration: 158.168197ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:47:54.8193Z","caller":"traceutil/trace.go:171","msg":"trace[93900586] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"103.32161ms","start":"2026-06-09T15:47:54.715956Z","end":"2026-06-09T15:47:54.819278Z","steps":["trace[93900586] 'process raft request' (duration: 103.187388ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:47:54.822684Z","caller":"traceutil/trace.go:171","msg":"trace[2133239468] transaction","detail":"{read_only:false; response_revision:2533; number_of_response:1; }","duration":"101.701699ms","start":"2026-06-09T15:47:54.720949Z","end":"2026-06-09T15:47:54.822651Z","steps":["trace[2133239468] 'process raft request' (duration: 101.442176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:48:03.042538Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933266405530726219,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T15:48:03.042792Z","caller":"traceutil/trace.go:171","msg":"trace[1005901685] linearizableReadLoop","detail":"{readStateIndex:2726; appliedIndex:2725; }","duration":"500.478353ms","start":"2026-06-09T15:48:02.542282Z","end":"2026-06-09T15:48:03.04276Z","steps":["trace[1005901685] 'read index received' (duration: 500.456493ms)","trace[1005901685] 'applied index is now lower than readState.Index' (duration: 20.7µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:48:03.04287Z","caller":"traceutil/trace.go:171","msg":"trace[664961350] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"553.74406ms","start":"2026-06-09T15:48:02.489092Z","end":"2026-06-09T15:48:03.042836Z","steps":["trace[664961350] 'process raft request' (duration: 553.511447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:48:03.043004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.707085ms","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-06-09T15:48:03.043046Z","caller":"traceutil/trace.go:171","msg":"trace[695227972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2628; }","duration":"500.793796ms","start":"2026-06-09T15:48:02.542241Z","end":"2026-06-09T15:48:03.043035Z","steps":["trace[695227972] 'agreement among raft nodes before linearized reading' (duration: 500.640054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:48:03.043083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:48:02.542224Z","time spent":"500.851477ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T15:48:03.043087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:48:02.489075Z","time spent":"553.922023ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8747,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:48:03.067016Z","caller":"traceutil/trace.go:171","msg":"trace[776188391] transaction","detail":"{read_only:false; response_revision:2629; number_of_response:1; }","duration":"226.45075ms","start":"2026-06-09T15:48:02.840547Z","end":"2026-06-09T15:48:03.066998Z","steps":["trace[776188391] 'process raft request' (duration: 226.313388ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:48:03.067452Z","caller":"traceutil/trace.go:171","msg":"trace[1670307294] transaction","detail":"{read_only:false; response_revision:2630; number_of_response:1; }","duration":"169.481429ms","start":"2026-06-09T15:48:02.897943Z","end":"2026-06-09T15:48:03.067424Z","steps":["trace[1670307294] 'process raft request' (duration: 169.017653ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:48:03.068159Z","caller":"traceutil/trace.go:171","msg":"trace[1173955220] transaction","detail":"{read_only:false; response_revision:2632; number_of_response:1; }","duration":"166.576664ms","start":"2026-06-09T15:48:02.901558Z","end":"2026-06-09T15:48:03.068135Z","steps":["trace[1173955220] 'process raft request' (duration: 166.433893ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:48:03.068196Z","caller":"traceutil/trace.go:171","msg":"trace[762711438] transaction","detail":"{read_only:false; response_revision:2631; number_of_response:1; }","duration":"167.499495ms","start":"2026-06-09T15:48:02.900683Z","end":"2026-06-09T15:48:03.068182Z","steps":["trace[762711438] 'process raft request' (duration: 166.627004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:48:03.068448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.240834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-09T15:48:03.06849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.632823ms","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-06-09T15:48:03.068516Z","caller":"traceutil/trace.go:171","msg":"trace[686702254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2633; }","duration":"112.350784ms","start":"2026-06-09T15:48:02.956147Z","end":"2026-06-09T15:48:03.068498Z","steps":["trace[686702254] 'agreement among raft nodes before linearized reading' (duration: 112.182282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:48:03.068516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.48973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-09T15:48:03.068563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.507719ms","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-06-09T15:48:03.06853Z","caller":"traceutil/trace.go:171","msg":"trace[1332614531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2633; }","duration":"510.709074ms","start":"2026-06-09T15:48:02.557808Z","end":"2026-06-09T15:48:03.068517Z","steps":["trace[1332614531] 'agreement among raft nodes before linearized reading' (duration: 510.571112ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:48:03.068594Z","caller":"traceutil/trace.go:171","msg":"trace[1597197435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2633; }","duration":"103.553019ms","start":"2026-06-09T15:48:02.965028Z","end":"2026-06-09T15:48:03.068581Z","steps":["trace[1597197435] 'agreement among raft nodes before linearized reading' (duration: 103.458128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:48:03.068447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.458111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:48:03.068613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:48:02.557794Z","time spent":"510.807855ms","remote":"127.0.0.1:44606","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":"info","ts":"2026-06-09T15:48:03.068643Z","caller":"traceutil/trace.go:171","msg":"trace[581849918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2633; }","duration":"514.599261ms","start":"2026-06-09T15:48:02.553958Z","end":"2026-06-09T15:48:03.068557Z","steps":["trace[581849918] 'agreement among raft nodes before linearized reading' (duration: 514.214987ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:48:03.068662Z","caller":"traceutil/trace.go:171","msg":"trace[1401516687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2633; }","duration":"337.725963ms","start":"2026-06-09T15:48:02.730918Z","end":"2026-06-09T15:48:03.068644Z","steps":["trace[1401516687] 'agreement among raft nodes before linearized reading' (duration: 337.45261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:48:03.068707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:48:02.730898Z","time spent":"337.795305ms","remote":"127.0.0.1:44290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T15:48:03.068704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:48:02.553926Z","time spent":"514.760523ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T15:48:36.56841Z","caller":"traceutil/trace.go:171","msg":"trace[1863035604] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"191.58031ms","start":"2026-06-09T15:48:36.376802Z","end":"2026-06-09T15:48:36.568383Z","steps":["trace[1863035604] 'process raft request' (duration: 191.386294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:48:37.285145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.810413ms","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-06-09T15:48:37.285241Z","caller":"traceutil/trace.go:171","msg":"trace[1672697668] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2817; }","duration":"202.986218ms","start":"2026-06-09T15:48:37.082229Z","end":"2026-06-09T15:48:37.285216Z","steps":["trace[1672697668] 'range keys from in-memory index tree' (duration: 202.452282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:48:37.285437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.093664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:48:37.285503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.953111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T15:48:37.285756Z","caller":"traceutil/trace.go:171","msg":"trace[1540909147] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2817; }","duration":"456.330792ms","start":"2026-06-09T15:48:36.829346Z","end":"2026-06-09T15:48:37.285677Z","steps":["trace[1540909147] 'count revisions from in-memory index tree' (duration: 455.853307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:48:37.285916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:48:36.829307Z","time spent":"456.511479ms","remote":"127.0.0.1:44486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-06-09T15:48:37.285557Z","caller":"traceutil/trace.go:171","msg":"trace[1539486537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2817; }","duration":"554.290431ms","start":"2026-06-09T15:48:36.73124Z","end":"2026-06-09T15:48:37.285531Z","steps":["trace[1539486537] 'range keys from in-memory index tree' (duration: 553.96479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:48:37.286145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:48:36.731209Z","time spent":"554.89075ms","remote":"127.0.0.1:44290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T15:52:16.821395Z","caller":"traceutil/trace.go:171","msg":"trace[642231303] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"144.788894ms","start":"2026-06-09T15:52:16.676571Z","end":"2026-06-09T15:52:16.82136Z","steps":["trace[642231303] 'process raft request' (duration: 78.758878ms)","trace[642231303] 'compare' (duration: 65.804622ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:16.82797Z","caller":"traceutil/trace.go:171","msg":"trace[1196199223] transaction","detail":"{read_only:false; response_revision:4496; number_of_response:1; }","duration":"150.727483ms","start":"2026-06-09T15:52:16.677224Z","end":"2026-06-09T15:52:16.827952Z","steps":["trace[1196199223] 'process raft request' (duration: 150.5406ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:16.828228Z","caller":"traceutil/trace.go:171","msg":"trace[1771797308] transaction","detail":"{read_only:false; response_revision:4497; number_of_response:1; }","duration":"106.437111ms","start":"2026-06-09T15:52:16.721758Z","end":"2026-06-09T15:52:16.828195Z","steps":["trace[1771797308] 'process raft request' (duration: 106.139696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:18.744893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.104614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-09T15:52:18.745034Z","caller":"traceutil/trace.go:171","msg":"trace[1975490758] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4520; }","duration":"108.324887ms","start":"2026-06-09T15:52:18.63668Z","end":"2026-06-09T15:52:18.745005Z","steps":["trace[1975490758] 'range keys from in-memory index tree' (duration: 107.818619ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:20.298803Z","caller":"traceutil/trace.go:171","msg":"trace[1533853050] transaction","detail":"{read_only:false; response_revision:4594; number_of_response:1; }","duration":"189.918021ms","start":"2026-06-09T15:52:20.10886Z","end":"2026-06-09T15:52:20.298778Z","steps":["trace[1533853050] 'process raft request' (duration: 136.458971ms)","trace[1533853050] 'compare' (duration: 53.112964ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:20.314225Z","caller":"traceutil/trace.go:171","msg":"trace[1186756309] transaction","detail":"{read_only:false; response_revision:4595; number_of_response:1; }","duration":"130.234566ms","start":"2026-06-09T15:52:20.183938Z","end":"2026-06-09T15:52:20.314172Z","steps":["trace[1186756309] 'process raft request' (duration: 129.991672ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:20.842394Z","caller":"traceutil/trace.go:171","msg":"trace[367545305] transaction","detail":"{read_only:false; response_revision:4618; number_of_response:1; }","duration":"110.466177ms","start":"2026-06-09T15:52:20.731872Z","end":"2026-06-09T15:52:20.842338Z","steps":["trace[367545305] 'process raft request' (duration: 110.01956ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:20.957121Z","caller":"traceutil/trace.go:171","msg":"trace[970670795] transaction","detail":"{read_only:false; response_revision:4620; number_of_response:1; }","duration":"107.028591ms","start":"2026-06-09T15:52:20.850076Z","end":"2026-06-09T15:52:20.957105Z","steps":["trace[970670795] 'process raft request' (duration: 106.97302ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:20.957314Z","caller":"traceutil/trace.go:171","msg":"trace[1269060207] transaction","detail":"{read_only:false; response_revision:4619; number_of_response:1; }","duration":"109.159616ms","start":"2026-06-09T15:52:20.848135Z","end":"2026-06-09T15:52:20.957295Z","steps":["trace[1269060207] 'process raft request' (duration: 51.621048ms)","trace[1269060207] 'compare' (duration: 57.170742ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:20.957284Z","caller":"traceutil/trace.go:171","msg":"trace[1369728604] linearizableReadLoop","detail":"{readStateIndex:4795; appliedIndex:4792; }","duration":"103.274139ms","start":"2026-06-09T15:52:20.853984Z","end":"2026-06-09T15:52:20.957258Z","steps":["trace[1369728604] 'read index received' (duration: 45.788422ms)","trace[1369728604] 'applied index is now lower than readState.Index' (duration: 57.484427ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:20.957398Z","caller":"traceutil/trace.go:171","msg":"trace[678429237] transaction","detail":"{read_only:false; response_revision:4621; number_of_response:1; }","duration":"107.246115ms","start":"2026-06-09T15:52:20.85014Z","end":"2026-06-09T15:52:20.957386Z","steps":["trace[678429237] 'process raft request' (duration: 106.94046ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:20.957629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.633634ms","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-06-09T15:52:20.957654Z","caller":"traceutil/trace.go:171","msg":"trace[1656317406] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4621; }","duration":"103.726416ms","start":"2026-06-09T15:52:20.85392Z","end":"2026-06-09T15:52:20.957647Z","steps":["trace[1656317406] 'agreement among raft nodes before linearized reading' (duration: 103.599454ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:23.725767Z","caller":"traceutil/trace.go:171","msg":"trace[146537014] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"112.536384ms","start":"2026-06-09T15:52:23.613197Z","end":"2026-06-09T15:52:23.725733Z","steps":["trace[146537014] 'process raft request' (duration: 97.973523ms)","trace[146537014] 'compare' (duration: 14.213335ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:52:25.958068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.641191ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-06-09T15:52:25.996641Z","caller":"traceutil/trace.go:171","msg":"trace[1491799133] linearizableReadLoop","detail":"{readStateIndex:4893; appliedIndex:4891; }","duration":"265.907094ms","start":"2026-06-09T15:52:25.730706Z","end":"2026-06-09T15:52:25.996613Z","steps":["trace[1491799133] 'read index received' (duration: 65.722086ms)","trace[1491799133] 'applied index is now lower than readState.Index' (duration: 200.184158ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:25.99667Z","caller":"traceutil/trace.go:171","msg":"trace[31279915] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"271.541686ms","start":"2026-06-09T15:52:25.72509Z","end":"2026-06-09T15:52:25.996632Z","steps":["trace[31279915] 'process raft request' (duration: 271.261972ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:25.996953Z","caller":"traceutil/trace.go:171","msg":"trace[279443039] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"236.249993ms","start":"2026-06-09T15:52:25.760689Z","end":"2026-06-09T15:52:25.996939Z","steps":["trace[279443039] 'process raft request' (duration: 235.868787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:25.996942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.217789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:52:25.997004Z","caller":"traceutil/trace.go:171","msg":"trace[88318639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4716; }","duration":"266.32329ms","start":"2026-06-09T15:52:25.730667Z","end":"2026-06-09T15:52:25.99699Z","steps":["trace[88318639] 'agreement among raft nodes before linearized reading' (duration: 266.153917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:26.003755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.365606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2209737"} {"level":"info","ts":"2026-06-09T15:52:26.003811Z","caller":"traceutil/trace.go:171","msg":"trace[987492313] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4717; }","duration":"115.464437ms","start":"2026-06-09T15:52:25.888334Z","end":"2026-06-09T15:52:26.003798Z","steps":["trace[987492313] 'agreement among raft nodes before linearized reading' (duration: 114.4088ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:26.518696Z","caller":"traceutil/trace.go:171","msg":"trace[749765714] linearizableReadLoop","detail":"{readStateIndex:4903; appliedIndex:4902; }","duration":"275.350503ms","start":"2026-06-09T15:52:26.243329Z","end":"2026-06-09T15:52:26.51868Z","steps":["trace[749765714] 'read index received' (duration: 275.297312ms)","trace[749765714] 'applied index is now lower than readState.Index' (duration: 52.581µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:26.518927Z","caller":"traceutil/trace.go:171","msg":"trace[1410741235] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"277.441638ms","start":"2026-06-09T15:52:26.241475Z","end":"2026-06-09T15:52:26.518917Z","steps":["trace[1410741235] 'process raft request' (duration: 277.096852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:26.519123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.77884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-06-09T15:52:26.519145Z","caller":"traceutil/trace.go:171","msg":"trace[37731988] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4724; }","duration":"275.823811ms","start":"2026-06-09T15:52:26.243316Z","end":"2026-06-09T15:52:26.519139Z","steps":["trace[37731988] 'agreement among raft nodes before linearized reading' (duration: 275.7329ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:26.533374Z","caller":"traceutil/trace.go:171","msg":"trace[441303209] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"200.104971ms","start":"2026-06-09T15:52:26.333245Z","end":"2026-06-09T15:52:26.53335Z","steps":["trace[441303209] 'process raft request' (duration: 199.939949ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:26.533603Z","caller":"traceutil/trace.go:171","msg":"trace[1603688817] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"175.549967ms","start":"2026-06-09T15:52:26.358037Z","end":"2026-06-09T15:52:26.533587Z","steps":["trace[1603688817] 'process raft request' (duration: 175.231291ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:26.533911Z","caller":"traceutil/trace.go:171","msg":"trace[398591180] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"140.625781ms","start":"2026-06-09T15:52:26.393277Z","end":"2026-06-09T15:52:26.533903Z","steps":["trace[398591180] 'process raft request' (duration: 140.037821ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:26.534053Z","caller":"traceutil/trace.go:171","msg":"trace[1734807734] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"140.711903ms","start":"2026-06-09T15:52:26.393334Z","end":"2026-06-09T15:52:26.534046Z","steps":["trace[1734807734] 'process raft request' (duration: 140.00913ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:30.486867Z","caller":"traceutil/trace.go:171","msg":"trace[1077880339] linearizableReadLoop","detail":"{readStateIndex:4987; appliedIndex:4986; }","duration":"425.255959ms","start":"2026-06-09T15:52:30.061585Z","end":"2026-06-09T15:52:30.486841Z","steps":["trace[1077880339] 'read index received' (duration: 424.998905ms)","trace[1077880339] 'applied index is now lower than readState.Index' (duration: 255.894µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:30.486945Z","caller":"traceutil/trace.go:171","msg":"trace[163826693] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"541.676151ms","start":"2026-06-09T15:52:29.945233Z","end":"2026-06-09T15:52:30.486909Z","steps":["trace[163826693] 'process raft request' (duration: 541.455297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:30.487102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:29.94521Z","time spent":"541.809873ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:52:30.487197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.043454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da7a718a-6dad-4115-8d4c-28f2a2e075c7\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-06-09T15:52:30.487273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.653679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-zrbp5-tls\" ","response":"range_response_count:1 size:2182"} {"level":"info","ts":"2026-06-09T15:52:30.487265Z","caller":"traceutil/trace.go:171","msg":"trace[1833632015] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da7a718a-6dad-4115-8d4c-28f2a2e075c7; range_end:; response_count:1; response_revision:4807; }","duration":"378.140775ms","start":"2026-06-09T15:52:30.109106Z","end":"2026-06-09T15:52:30.487246Z","steps":["trace[1833632015] 'agreement among raft nodes before linearized reading' (duration: 377.961212ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:30.487322Z","caller":"traceutil/trace.go:171","msg":"trace[946218859] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-zrbp5-tls; range_end:; response_count:1; response_revision:4807; }","duration":"148.744751ms","start":"2026-06-09T15:52:30.338565Z","end":"2026-06-09T15:52:30.48731Z","steps":["trace[946218859] 'agreement among raft nodes before linearized reading' (duration: 148.535728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:30.48733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:30.109093Z","time spent":"378.227916ms","remote":"127.0.0.1:44504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da7a718a-6dad-4115-8d4c-28f2a2e075c7\" "} {"level":"warn","ts":"2026-06-09T15:52:30.487051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.434331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:52:30.487781Z","caller":"traceutil/trace.go:171","msg":"trace[1769713019] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4807; }","duration":"426.182524ms","start":"2026-06-09T15:52:30.061581Z","end":"2026-06-09T15:52:30.487763Z","steps":["trace[1769713019] 'agreement among raft nodes before linearized reading' (duration: 425.410471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:30.487847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:30.061546Z","time spent":"426.281465ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"info","ts":"2026-06-09T15:52:31.127771Z","caller":"traceutil/trace.go:171","msg":"trace[339776090] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"121.792117ms","start":"2026-06-09T15:52:31.005961Z","end":"2026-06-09T15:52:31.127753Z","steps":["trace[339776090] 'process raft request' (duration: 121.667474ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:31.381078Z","caller":"traceutil/trace.go:171","msg":"trace[2047968033] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"374.413126ms","start":"2026-06-09T15:52:31.006624Z","end":"2026-06-09T15:52:31.381037Z","steps":["trace[2047968033] 'process raft request' (duration: 335.429567ms)","trace[2047968033] 'compare' (duration: 38.527562ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:31.381122Z","caller":"traceutil/trace.go:171","msg":"trace[98523891] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"374.348155ms","start":"2026-06-09T15:52:31.006754Z","end":"2026-06-09T15:52:31.381102Z","steps":["trace[98523891] 'process raft request' (duration: 374.181272ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:31.381099Z","caller":"traceutil/trace.go:171","msg":"trace[640169878] linearizableReadLoop","detail":"{readStateIndex:4996; appliedIndex:4993; }","duration":"365.921947ms","start":"2026-06-09T15:52:31.015152Z","end":"2026-06-09T15:52:31.381073Z","steps":["trace[640169878] 'read index received' (duration: 112.481803ms)","trace[640169878] 'applied index is now lower than readState.Index' (duration: 253.438674ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:52:31.381256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:31.00674Z","time spent":"374.431416ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:52:31.381314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.15795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-09T15:52:31.38138Z","caller":"traceutil/trace.go:171","msg":"trace[409921815] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4816; }","duration":"366.245962ms","start":"2026-06-09T15:52:31.01512Z","end":"2026-06-09T15:52:31.381366Z","steps":["trace[409921815] 'agreement among raft nodes before linearized reading' (duration: 366.034249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:31.38143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:31.015109Z","time spent":"366.309943ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T15:52:31.381608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:31.00661Z","time spent":"374.552428ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:52:31.539336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.264113ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-09T15:52:31.540028Z","caller":"traceutil/trace.go:171","msg":"trace[1025463068] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"153.393344ms","start":"2026-06-09T15:52:31.386607Z","end":"2026-06-09T15:52:31.54Z","steps":["trace[1025463068] 'process raft request' (duration: 153.035169ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:32.953492Z","caller":"traceutil/trace.go:171","msg":"trace[1081159779] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"309.636746ms","start":"2026-06-09T15:52:32.643829Z","end":"2026-06-09T15:52:32.953466Z","steps":["trace[1081159779] 'process raft request' (duration: 256.838122ms)","trace[1081159779] 'compare' (duration: 52.51209ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:52:32.953635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:32.643813Z","time spent":"309.749208ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:52:39.617218Z","caller":"traceutil/trace.go:171","msg":"trace[495308374] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"178.84055ms","start":"2026-06-09T15:52:39.438335Z","end":"2026-06-09T15:52:39.617175Z","steps":["trace[495308374] 'process raft request' (duration: 178.629567ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:39.6185Z","caller":"traceutil/trace.go:171","msg":"trace[884498048] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"179.953128ms","start":"2026-06-09T15:52:39.438505Z","end":"2026-06-09T15:52:39.618458Z","steps":["trace[884498048] 'process raft request' (duration: 179.796596ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:44.346336Z","caller":"traceutil/trace.go:171","msg":"trace[2040034957] linearizableReadLoop","detail":"{readStateIndex:5163; appliedIndex:5162; }","duration":"236.236647ms","start":"2026-06-09T15:52:44.110073Z","end":"2026-06-09T15:52:44.34631Z","steps":["trace[2040034957] 'read index received' (duration: 236.002853ms)","trace[2040034957] 'applied index is now lower than readState.Index' (duration: 155.462µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:44.346414Z","caller":"traceutil/trace.go:171","msg":"trace[824576718] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"239.419918ms","start":"2026-06-09T15:52:44.106959Z","end":"2026-06-09T15:52:44.346379Z","steps":["trace[824576718] 'process raft request' (duration: 239.112973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:44.346531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-06-09T15:52:44.346569Z","caller":"traceutil/trace.go:171","msg":"trace[101776291] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4976; }","duration":"236.517341ms","start":"2026-06-09T15:52:44.11004Z","end":"2026-06-09T15:52:44.346557Z","steps":["trace[101776291] 'agreement among raft nodes before linearized reading' (duration: 236.366228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:44.971804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.553701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:52:44.972334Z","caller":"traceutil/trace.go:171","msg":"trace[187764618] linearizableReadLoop","detail":"{readStateIndex:5164; appliedIndex:5163; }","duration":"622.3632ms","start":"2026-06-09T15:52:44.349938Z","end":"2026-06-09T15:52:44.972301Z","steps":["trace[187764618] 'read index received' (duration: 480.090317ms)","trace[187764618] 'applied index is now lower than readState.Index' (duration: 142.267602ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:44.972436Z","caller":"traceutil/trace.go:171","msg":"trace[1934754704] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"295.142586ms","start":"2026-06-09T15:52:44.677261Z","end":"2026-06-09T15:52:44.972404Z","steps":["trace[1934754704] 'process raft request' (duration: 295.061085ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:44.972463Z","caller":"traceutil/trace.go:171","msg":"trace[118426338] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"646.787153ms","start":"2026-06-09T15:52:44.325663Z","end":"2026-06-09T15:52:44.97245Z","steps":["trace[118426338] 'process raft request' (duration: 504.403729ms)","trace[118426338] 'compare' (duration: 141.245025ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:44.972614Z","caller":"traceutil/trace.go:171","msg":"trace[1817322691] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"310.350511ms","start":"2026-06-09T15:52:44.662206Z","end":"2026-06-09T15:52:44.972557Z","steps":["trace[1817322691] 'process raft request' (duration: 309.779252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:44.972755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.48018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-06-09T15:52:44.972795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:44.662183Z","time spent":"310.509514ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:52:44.972806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:44.325642Z","time spent":"647.008466ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:52:44.972825Z","caller":"traceutil/trace.go:171","msg":"trace[2097660861] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"300.649586ms","start":"2026-06-09T15:52:44.672165Z","end":"2026-06-09T15:52:44.972815Z","steps":["trace[2097660861] 'process raft request' (duration: 299.983405ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:44.972847Z","caller":"traceutil/trace.go:171","msg":"trace[1508704666] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4981; }","duration":"622.594853ms","start":"2026-06-09T15:52:44.35023Z","end":"2026-06-09T15:52:44.972825Z","steps":["trace[1508704666] 'agreement among raft nodes before linearized reading' (duration: 622.329809ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:44.972897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:44.350225Z","time spent":"622.660034ms","remote":"127.0.0.1:44486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-09T15:52:44.972902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.963699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18b774923f56b0d1\" ","response":"range_response_count:1 size:740"} {"level":"warn","ts":"2026-06-09T15:52:44.972908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:44.672153Z","time spent":"300.704076ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:52:44.97296Z","caller":"traceutil/trace.go:171","msg":"trace[1174541548] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18b774923f56b0d1; range_end:; response_count:1; response_revision:4981; }","duration":"623.045681ms","start":"2026-06-09T15:52:44.349895Z","end":"2026-06-09T15:52:44.97294Z","steps":["trace[1174541548] 'agreement among raft nodes before linearized reading' (duration: 622.912929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:44.973016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.938692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T15:52:44.972754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.696114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-09T15:52:44.973076Z","caller":"traceutil/trace.go:171","msg":"trace[475750082] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:4981; }","duration":"393.049104ms","start":"2026-06-09T15:52:44.580011Z","end":"2026-06-09T15:52:44.97306Z","steps":["trace[475750082] 'agreement among raft nodes before linearized reading' (duration: 392.873231ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:44.973074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.097734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T15:52:44.973078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.298052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T15:52:44.973102Z","caller":"traceutil/trace.go:171","msg":"trace[1440627136] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4981; }","duration":"237.104021ms","start":"2026-06-09T15:52:44.735987Z","end":"2026-06-09T15:52:44.973091Z","steps":["trace[1440627136] 'agreement among raft nodes before linearized reading' (duration: 236.608373ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:44.973118Z","caller":"traceutil/trace.go:171","msg":"trace[801268973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4981; }","duration":"244.180144ms","start":"2026-06-09T15:52:44.728925Z","end":"2026-06-09T15:52:44.973106Z","steps":["trace[801268973] 'agreement among raft nodes before linearized reading' (duration: 244.098213ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:44.973121Z","caller":"traceutil/trace.go:171","msg":"trace[1794059550] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:4981; }","duration":"585.343152ms","start":"2026-06-09T15:52:44.387768Z","end":"2026-06-09T15:52:44.973111Z","steps":["trace[1794059550] 'agreement among raft nodes before linearized reading' (duration: 585.258641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:44.973155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.335094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-06-09T15:52:44.973166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:44.387704Z","time spent":"585.454564ms","remote":"127.0.0.1:44820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":17,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-06-09T15:52:44.97277Z","caller":"traceutil/trace.go:171","msg":"trace[24067053] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"300.226608ms","start":"2026-06-09T15:52:44.67252Z","end":"2026-06-09T15:52:44.972747Z","steps":["trace[24067053] 'process raft request' (duration: 299.696309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:44.973026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:44.349878Z","time spent":"623.127702ms","remote":"127.0.0.1:44390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":763,"request content":"key:\"/registry/events/openstack/rabbitmq-keystone.18b774923f56b0d1\" "} {"level":"warn","ts":"2026-06-09T15:52:44.973339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:44.67249Z","time spent":"300.761777ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:52:44.973201Z","caller":"traceutil/trace.go:171","msg":"trace[770303595] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4981; }","duration":"155.418005ms","start":"2026-06-09T15:52:44.817769Z","end":"2026-06-09T15:52:44.973187Z","steps":["trace[770303595] 'agreement among raft nodes before linearized reading' (duration: 155.283832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:44.97312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:52:44.579992Z","time spent":"393.116194ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":71,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-06-09T15:52:45.181926Z","caller":"traceutil/trace.go:171","msg":"trace[840965555] linearizableReadLoop","detail":"{readStateIndex:5171; appliedIndex:5169; }","duration":"137.414012ms","start":"2026-06-09T15:52:45.044475Z","end":"2026-06-09T15:52:45.181889Z","steps":["trace[840965555] 'read index received' (duration: 12.40319ms)","trace[840965555] 'applied index is now lower than readState.Index' (duration: 125.009352ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:45.1821Z","caller":"traceutil/trace.go:171","msg":"trace[914290325] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"155.506954ms","start":"2026-06-09T15:52:45.026571Z","end":"2026-06-09T15:52:45.182078Z","steps":["trace[914290325] 'process raft request' (duration: 84.221936ms)","trace[914290325] 'compare' (duration: 70.944892ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:52:45.182211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.482984ms","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-06-09T15:52:45.182283Z","caller":"traceutil/trace.go:171","msg":"trace[1433351235] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4984; }","duration":"111.602837ms","start":"2026-06-09T15:52:45.070664Z","end":"2026-06-09T15:52:45.182266Z","steps":["trace[1433351235] 'agreement among raft nodes before linearized reading' (duration: 111.437134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:45.18268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.193745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5b5fd65fd8-rtjgw\" ","response":"range_response_count:1 size:11843"} {"level":"info","ts":"2026-06-09T15:52:45.182747Z","caller":"traceutil/trace.go:171","msg":"trace[1058420580] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-5b5fd65fd8-rtjgw; range_end:; response_count:1; response_revision:4984; }","duration":"138.289176ms","start":"2026-06-09T15:52:45.044437Z","end":"2026-06-09T15:52:45.182726Z","steps":["trace[1058420580] 'agreement among raft nodes before linearized reading' (duration: 138.080933ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:45.440452Z","caller":"traceutil/trace.go:171","msg":"trace[902313244] linearizableReadLoop","detail":"{readStateIndex:5172; appliedIndex:5171; }","duration":"243.321577ms","start":"2026-06-09T15:52:45.197106Z","end":"2026-06-09T15:52:45.440428Z","steps":["trace[902313244] 'read index received' (duration: 243.009922ms)","trace[902313244] 'applied index is now lower than readState.Index' (duration: 310.795µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:45.44058Z","caller":"traceutil/trace.go:171","msg":"trace[967752616] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"246.985965ms","start":"2026-06-09T15:52:45.193583Z","end":"2026-06-09T15:52:45.440569Z","steps":["trace[967752616] 'process raft request' (duration: 246.643109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:45.440921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.792704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-06-09T15:52:45.440964Z","caller":"traceutil/trace.go:171","msg":"trace[1514024714] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4985; }","duration":"243.864245ms","start":"2026-06-09T15:52:45.197089Z","end":"2026-06-09T15:52:45.440953Z","steps":["trace[1514024714] 'agreement among raft nodes before linearized reading' (duration: 243.713973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:52:45.441192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.774116ms","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-06-09T15:52:45.441266Z","caller":"traceutil/trace.go:171","msg":"trace[718313612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4985; }","duration":"232.879508ms","start":"2026-06-09T15:52:45.20837Z","end":"2026-06-09T15:52:45.44125Z","steps":["trace[718313612] 'agreement among raft nodes before linearized reading' (duration: 232.648684ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:45.713917Z","caller":"traceutil/trace.go:171","msg":"trace[1709196330] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"266.222936ms","start":"2026-06-09T15:52:45.447665Z","end":"2026-06-09T15:52:45.713888Z","steps":["trace[1709196330] 'process raft request' (duration: 223.757712ms)","trace[1709196330] 'compare' (duration: 42.295051ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:45.74048Z","caller":"traceutil/trace.go:171","msg":"trace[881861419] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"292.682832ms","start":"2026-06-09T15:52:45.447769Z","end":"2026-06-09T15:52:45.740452Z","steps":["trace[881861419] 'process raft request' (duration: 292.348126ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:45.740765Z","caller":"traceutil/trace.go:171","msg":"trace[1725260929] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"292.769483ms","start":"2026-06-09T15:52:45.44797Z","end":"2026-06-09T15:52:45.740739Z","steps":["trace[1725260929] 'process raft request' (duration: 292.383757ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:45.740796Z","caller":"traceutil/trace.go:171","msg":"trace[248366190] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"288.370082ms","start":"2026-06-09T15:52:45.45241Z","end":"2026-06-09T15:52:45.74078Z","steps":["trace[248366190] 'process raft request' (duration: 288.047147ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:45.923569Z","caller":"traceutil/trace.go:171","msg":"trace[1274546012] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"177.07033ms","start":"2026-06-09T15:52:45.746472Z","end":"2026-06-09T15:52:45.923542Z","steps":["trace[1274546012] 'process raft request' (duration: 99.854088ms)","trace[1274546012] 'compare' (duration: 76.990848ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:45.923915Z","caller":"traceutil/trace.go:171","msg":"trace[593926384] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"175.769739ms","start":"2026-06-09T15:52:45.748127Z","end":"2026-06-09T15:52:45.923896Z","steps":["trace[593926384] 'process raft request' (duration: 175.663617ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:45.923918Z","caller":"traceutil/trace.go:171","msg":"trace[189816028] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"176.137596ms","start":"2026-06-09T15:52:45.747754Z","end":"2026-06-09T15:52:45.923892Z","steps":["trace[189816028] 'process raft request' (duration: 175.743429ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:45.923942Z","caller":"traceutil/trace.go:171","msg":"trace[162287623] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"172.527686ms","start":"2026-06-09T15:52:45.751403Z","end":"2026-06-09T15:52:45.923931Z","steps":["trace[162287623] 'process raft request' (duration: 172.448415ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:45.92421Z","caller":"traceutil/trace.go:171","msg":"trace[1218600680] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"156.513708ms","start":"2026-06-09T15:52:45.767686Z","end":"2026-06-09T15:52:45.9242Z","steps":["trace[1218600680] 'process raft request' (duration: 156.215504ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:47.862342Z","caller":"traceutil/trace.go:171","msg":"trace[2026059987] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"100.682807ms","start":"2026-06-09T15:52:47.761636Z","end":"2026-06-09T15:52:47.862319Z","steps":["trace[2026059987] 'process raft request' (duration: 94.701371ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:52:55.981992Z","caller":"traceutil/trace.go:171","msg":"trace[1085449740] transaction","detail":"{read_only:false; response_revision:5082; number_of_response:1; }","duration":"106.297541ms","start":"2026-06-09T15:52:55.875679Z","end":"2026-06-09T15:52:55.981976Z","steps":["trace[1085449740] 'process raft request' (duration: 33.724407ms)","trace[1085449740] 'compare' (duration: 72.433132ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:52:55.98225Z","caller":"traceutil/trace.go:171","msg":"trace[458126091] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"102.351819ms","start":"2026-06-09T15:52:55.87985Z","end":"2026-06-09T15:52:55.982202Z","steps":["trace[458126091] 'process raft request' (duration: 102.089395ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:53:21.022101Z","caller":"traceutil/trace.go:171","msg":"trace[389133295] linearizableReadLoop","detail":"{readStateIndex:5519; appliedIndex:5518; }","duration":"214.877688ms","start":"2026-06-09T15:53:20.807183Z","end":"2026-06-09T15:53:21.022061Z","steps":["trace[389133295] 'read index received' (duration: 143.420272ms)","trace[389133295] 'applied index is now lower than readState.Index' (duration: 71.456386ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:53:21.022387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.174642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:53:21.022454Z","caller":"traceutil/trace.go:171","msg":"trace[37900440] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:5323; }","duration":"215.313214ms","start":"2026-06-09T15:53:20.80713Z","end":"2026-06-09T15:53:21.022443Z","steps":["trace[37900440] 'agreement among raft nodes before linearized reading' (duration: 215.128812ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:53:23.462269Z","caller":"traceutil/trace.go:171","msg":"trace[824295497] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"366.664373ms","start":"2026-06-09T15:53:23.095577Z","end":"2026-06-09T15:53:23.462242Z","steps":["trace[824295497] 'process raft request' (duration: 366.506581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:23.462435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:53:23.095546Z","time spent":"366.800446ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:53:23.604386Z","caller":"traceutil/trace.go:171","msg":"trace[1980725610] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"506.730227ms","start":"2026-06-09T15:53:23.09763Z","end":"2026-06-09T15:53:23.60436Z","steps":["trace[1980725610] 'process raft request' (duration: 492.021831ms)","trace[1980725610] 'compare' (duration: 14.536314ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:53:23.604533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:53:23.097617Z","time spent":"506.841089ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:53:23.604567Z","caller":"traceutil/trace.go:171","msg":"trace[732814000] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"440.345411ms","start":"2026-06-09T15:53:23.164202Z","end":"2026-06-09T15:53:23.604547Z","steps":["trace[732814000] 'process raft request' (duration: 440.113708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:23.60468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:53:23.164167Z","time spent":"440.451263ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:53:23.604783Z","caller":"traceutil/trace.go:171","msg":"trace[1188325158] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5529; }","duration":"357.548943ms","start":"2026-06-09T15:53:23.247227Z","end":"2026-06-09T15:53:23.604776Z","steps":["trace[1188325158] 'read index received' (duration: 214.859559ms)","trace[1188325158] 'applied index is now lower than readState.Index' (duration: 142.688724ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:53:23.604913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.680605ms","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-06-09T15:53:23.604962Z","caller":"traceutil/trace.go:171","msg":"trace[1323209851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5337; }","duration":"357.739565ms","start":"2026-06-09T15:53:23.247194Z","end":"2026-06-09T15:53:23.604934Z","steps":["trace[1323209851] 'agreement among raft nodes before linearized reading' (duration: 357.608353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:23.60499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:53:23.24718Z","time spent":"357.804096ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T15:53:23.605003Z","caller":"traceutil/trace.go:171","msg":"trace[460285440] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"440.505454ms","start":"2026-06-09T15:53:23.16428Z","end":"2026-06-09T15:53:23.604786Z","steps":["trace[460285440] 'process raft request' (duration: 440.206359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:23.605208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:53:23.164241Z","time spent":"440.86975ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:53:39.78355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.965298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:53:39.783736Z","caller":"traceutil/trace.go:171","msg":"trace[2039084084] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5447; }","duration":"193.192741ms","start":"2026-06-09T15:53:39.590522Z","end":"2026-06-09T15:53:39.783715Z","steps":["trace[2039084084] 'count revisions from in-memory index tree' (duration: 192.911437ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:53:39.884843Z","caller":"traceutil/trace.go:171","msg":"trace[868853410] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"225.404543ms","start":"2026-06-09T15:53:39.659423Z","end":"2026-06-09T15:53:39.884828Z","steps":["trace[868853410] 'process raft request' (duration: 225.265581ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:53:40.01671Z","caller":"traceutil/trace.go:171","msg":"trace[1026921225] linearizableReadLoop","detail":"{readStateIndex:5650; appliedIndex:5647; }","duration":"267.196439ms","start":"2026-06-09T15:53:39.749484Z","end":"2026-06-09T15:53:40.016681Z","steps":["trace[1026921225] 'read index received' (duration: 135.25335ms)","trace[1026921225] 'applied index is now lower than readState.Index' (duration: 131.942089ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:53:40.017081Z","caller":"traceutil/trace.go:171","msg":"trace[86951636] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"355.727586ms","start":"2026-06-09T15:53:39.661341Z","end":"2026-06-09T15:53:40.017068Z","steps":["trace[86951636] 'process raft request' (duration: 353.527082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:40.017186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:53:39.661326Z","time spent":"355.791817ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:53:40.017403Z","caller":"traceutil/trace.go:171","msg":"trace[916890705] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"354.071901ms","start":"2026-06-09T15:53:39.66332Z","end":"2026-06-09T15:53:40.017392Z","steps":["trace[916890705] 'process raft request' (duration: 353.304339ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:40.017465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:53:39.663293Z","time spent":"354.140881ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:53:40.017629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.138633ms","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-06-09T15:53:40.017682Z","caller":"traceutil/trace.go:171","msg":"trace[11541555] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5450; }","duration":"268.223744ms","start":"2026-06-09T15:53:39.749444Z","end":"2026-06-09T15:53:40.017668Z","steps":["trace[11541555] 'agreement among raft nodes before linearized reading' (duration: 268.145643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:40.017877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.124232ms","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-06-09T15:53:40.017917Z","caller":"traceutil/trace.go:171","msg":"trace[329369821] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5450; }","duration":"268.198162ms","start":"2026-06-09T15:53:39.749707Z","end":"2026-06-09T15:53:40.017905Z","steps":["trace[329369821] 'agreement among raft nodes before linearized reading' (duration: 268.144692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:40.018334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.110899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-09T15:53:40.018391Z","caller":"traceutil/trace.go:171","msg":"trace[1771159299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5450; }","duration":"258.188551ms","start":"2026-06-09T15:53:39.760189Z","end":"2026-06-09T15:53:40.018378Z","steps":["trace[1771159299] 'agreement among raft nodes before linearized reading' (duration: 258.033769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:43.196637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.018755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-db-init-wgvj8\" ","response":"range_response_count:1 size:7265"} {"level":"info","ts":"2026-06-09T15:53:43.196737Z","caller":"traceutil/trace.go:171","msg":"trace[1985847664] range","detail":"{range_begin:/registry/pods/openstack/keystone-db-init-wgvj8; range_end:; response_count:1; response_revision:5469; }","duration":"109.159816ms","start":"2026-06-09T15:53:43.087555Z","end":"2026-06-09T15:53:43.196715Z","steps":["trace[1985847664] 'range keys from in-memory index tree' (duration: 108.847802ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:53:43.314111Z","caller":"traceutil/trace.go:171","msg":"trace[1830616188] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"106.602417ms","start":"2026-06-09T15:53:43.207471Z","end":"2026-06-09T15:53:43.314074Z","steps":["trace[1830616188] 'process raft request' (duration: 106.365174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:44.018444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.165937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T15:53:44.018511Z","caller":"traceutil/trace.go:171","msg":"trace[1192426838] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5471; }","duration":"169.283978ms","start":"2026-06-09T15:53:43.849212Z","end":"2026-06-09T15:53:44.018496Z","steps":["trace[1192426838] 'count revisions from in-memory index tree' (duration: 169.008375ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:53:44.018633Z","caller":"traceutil/trace.go:171","msg":"trace[544718525] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"115.040276ms","start":"2026-06-09T15:53:43.903557Z","end":"2026-06-09T15:53:44.018597Z","steps":["trace[544718525] 'process raft request' (duration: 28.247929ms)","trace[544718525] 'compare' (duration: 86.652255ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:53:44.24735Z","caller":"traceutil/trace.go:171","msg":"trace[494540075] linearizableReadLoop","detail":"{readStateIndex:5675; appliedIndex:5673; }","duration":"181.150407ms","start":"2026-06-09T15:53:44.066173Z","end":"2026-06-09T15:53:44.247323Z","steps":["trace[494540075] 'read index received' (duration: 6.55205ms)","trace[494540075] 'applied index is now lower than readState.Index' (duration: 174.597477ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:53:44.247539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.35546ms","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-06-09T15:53:44.247616Z","caller":"traceutil/trace.go:171","msg":"trace[250804503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5474; }","duration":"181.483242ms","start":"2026-06-09T15:53:44.06612Z","end":"2026-06-09T15:53:44.247604Z","steps":["trace[250804503] 'agreement among raft nodes before linearized reading' (duration: 181.29496ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:53:44.247718Z","caller":"traceutil/trace.go:171","msg":"trace[1375982904] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"218.97355ms","start":"2026-06-09T15:53:44.028573Z","end":"2026-06-09T15:53:44.247546Z","steps":["trace[1375982904] 'process raft request' (duration: 143.871871ms)","trace[1375982904] 'compare' (duration: 74.732013ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:53:44.594839Z","caller":"traceutil/trace.go:171","msg":"trace[1066485323] linearizableReadLoop","detail":"{readStateIndex:5678; appliedIndex:5677; }","duration":"241.786796ms","start":"2026-06-09T15:53:44.353027Z","end":"2026-06-09T15:53:44.594814Z","steps":["trace[1066485323] 'read index received' (duration: 172.662397ms)","trace[1066485323] 'applied index is now lower than readState.Index' (duration: 69.123089ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:53:44.594988Z","caller":"traceutil/trace.go:171","msg":"trace[2128515722] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"269.168801ms","start":"2026-06-09T15:53:44.325803Z","end":"2026-06-09T15:53:44.594972Z","steps":["trace[2128515722] 'process raft request' (duration: 199.959731ms)","trace[2128515722] 'compare' (duration: 68.749563ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:53:44.595427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.568532ms","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-06-09T15:53:44.595525Z","caller":"traceutil/trace.go:171","msg":"trace[1538806237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5477; }","duration":"239.713594ms","start":"2026-06-09T15:53:44.355787Z","end":"2026-06-09T15:53:44.595501Z","steps":["trace[1538806237] 'agreement among raft nodes before linearized reading' (duration: 239.473371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:44.595426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.381735ms","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-06-09T15:53:44.595747Z","caller":"traceutil/trace.go:171","msg":"trace[1353040453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5477; }","duration":"242.695739ms","start":"2026-06-09T15:53:44.352978Z","end":"2026-06-09T15:53:44.595674Z","steps":["trace[1353040453] 'agreement among raft nodes before linearized reading' (duration: 242.305523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:44.59586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.300597ms","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-06-09T15:53:44.596011Z","caller":"traceutil/trace.go:171","msg":"trace[516475710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5477; }","duration":"237.488021ms","start":"2026-06-09T15:53:44.358503Z","end":"2026-06-09T15:53:44.595991Z","steps":["trace[516475710] 'agreement among raft nodes before linearized reading' (duration: 236.915572ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:53:44.886886Z","caller":"traceutil/trace.go:171","msg":"trace[640469634] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"286.335202ms","start":"2026-06-09T15:53:44.600524Z","end":"2026-06-09T15:53:44.88686Z","steps":["trace[640469634] 'process raft request' (duration: 285.756383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:45.113588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.523774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T15:53:45.113738Z","caller":"traceutil/trace.go:171","msg":"trace[796014138] linearizableReadLoop","detail":"{readStateIndex:5681; appliedIndex:5678; }","duration":"383.150797ms","start":"2026-06-09T15:53:44.73057Z","end":"2026-06-09T15:53:45.113721Z","steps":["trace[796014138] 'read index received' (duration: 155.73138ms)","trace[796014138] 'applied index is now lower than readState.Index' (duration: 227.418797ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:53:45.113861Z","caller":"traceutil/trace.go:171","msg":"trace[2027185120] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"512.780574ms","start":"2026-06-09T15:53:44.601066Z","end":"2026-06-09T15:53:45.113847Z","steps":["trace[2027185120] 'process raft request' (duration: 370.935456ms)","trace[2027185120] 'compare' (duration: 141.415632ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:53:45.113929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:53:44.601039Z","time spent":"512.857665ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T15:53:45.113973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.029598ms","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":"warn","ts":"2026-06-09T15:53:45.113976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.386721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T15:53:45.11398Z","caller":"traceutil/trace.go:171","msg":"trace[171047704] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"512.466389ms","start":"2026-06-09T15:53:44.601494Z","end":"2026-06-09T15:53:45.11396Z","steps":["trace[171047704] 'process raft request' (duration: 512.184475ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:53:45.114027Z","caller":"traceutil/trace.go:171","msg":"trace[322205682] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:5480; }","duration":"151.136071ms","start":"2026-06-09T15:53:44.962875Z","end":"2026-06-09T15:53:45.114011Z","steps":["trace[322205682] 'agreement among raft nodes before linearized reading' (duration: 151.041219ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:53:45.114042Z","caller":"traceutil/trace.go:171","msg":"trace[1147454616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5480; }","duration":"383.495493ms","start":"2026-06-09T15:53:44.73053Z","end":"2026-06-09T15:53:45.114026Z","steps":["trace[1147454616] 'agreement among raft nodes before linearized reading' (duration: 383.366331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:53:45.11408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:53:44.730517Z","time spent":"383.555583ms","remote":"127.0.0.1:44290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T15:53:45.114087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T15:53:44.60148Z","time spent":"512.551241ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T15:53:52.863944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2901} {"level":"info","ts":"2026-06-09T15:53:52.984656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2901,"took":"118.252862ms","hash":1223688976,"current-db-size-bytes":53911552,"current-db-size":"54 MB","current-db-size-in-use-bytes":28979200,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-09T15:53:52.984739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1223688976,"revision":2901,"compact-revision":-1} {"level":"info","ts":"2026-06-09T15:54:21.941208Z","caller":"traceutil/trace.go:171","msg":"trace[215835237] transaction","detail":"{read_only:false; response_revision:5767; number_of_response:1; }","duration":"133.837976ms","start":"2026-06-09T15:54:21.807342Z","end":"2026-06-09T15:54:21.941179Z","steps":["trace[215835237] 'process raft request' (duration: 133.782605ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:54:21.941386Z","caller":"traceutil/trace.go:171","msg":"trace[624750906] transaction","detail":"{read_only:false; response_revision:5766; number_of_response:1; }","duration":"136.10819ms","start":"2026-06-09T15:54:21.805236Z","end":"2026-06-09T15:54:21.941344Z","steps":["trace[624750906] 'process raft request' (duration: 135.819006ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:54:21.941623Z","caller":"traceutil/trace.go:171","msg":"trace[1205018901] transaction","detail":"{read_only:false; response_revision:5765; number_of_response:1; }","duration":"140.146769ms","start":"2026-06-09T15:54:21.801447Z","end":"2026-06-09T15:54:21.941593Z","steps":["trace[1205018901] 'process raft request' (duration: 96.561975ms)","trace[1205018901] 'compare' (duration: 42.882214ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T15:54:21.962255Z","caller":"traceutil/trace.go:171","msg":"trace[1989909847] transaction","detail":"{read_only:false; response_revision:5768; number_of_response:1; }","duration":"107.379465ms","start":"2026-06-09T15:54:21.854845Z","end":"2026-06-09T15:54:21.962225Z","steps":["trace[1989909847] 'process raft request' (duration: 107.250223ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:54:21.962653Z","caller":"traceutil/trace.go:171","msg":"trace[46025399] linearizableReadLoop","detail":"{readStateIndex:5978; appliedIndex:5974; }","duration":"105.311444ms","start":"2026-06-09T15:54:21.857328Z","end":"2026-06-09T15:54:21.96264Z","steps":["trace[46025399] 'read index received' (duration: 40.69176ms)","trace[46025399] 'applied index is now lower than readState.Index' (duration: 64.618334ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T15:54:21.962883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.537687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-domain-manage-cmcw6\" ","response":"range_response_count:1 size:6511"} {"level":"info","ts":"2026-06-09T15:54:21.962973Z","caller":"traceutil/trace.go:171","msg":"trace[26038506] range","detail":"{range_begin:/registry/pods/openstack/keystone-domain-manage-cmcw6; range_end:; response_count:1; response_revision:5768; }","duration":"105.633049ms","start":"2026-06-09T15:54:21.857282Z","end":"2026-06-09T15:54:21.962915Z","steps":["trace[26038506] 'agreement among raft nodes before linearized reading' (duration: 105.409306ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:54:23.170313Z","caller":"traceutil/trace.go:171","msg":"trace[265092829] transaction","detail":"{read_only:false; response_revision:5780; number_of_response:1; }","duration":"150.740391ms","start":"2026-06-09T15:54:23.019538Z","end":"2026-06-09T15:54:23.170278Z","steps":["trace[265092829] 'process raft request' (duration: 150.506318ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:55:51.036169Z","caller":"traceutil/trace.go:171","msg":"trace[1917433787] transaction","detail":"{read_only:false; response_revision:6259; number_of_response:1; }","duration":"110.967503ms","start":"2026-06-09T15:55:50.925165Z","end":"2026-06-09T15:55:51.036132Z","steps":["trace[1917433787] 'process raft request' (duration: 110.72336ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:56:20.532541Z","caller":"traceutil/trace.go:171","msg":"trace[1422775907] transaction","detail":"{read_only:false; response_revision:6553; number_of_response:1; }","duration":"115.071206ms","start":"2026-06-09T15:56:20.417447Z","end":"2026-06-09T15:56:20.532518Z","steps":["trace[1422775907] 'process raft request' (duration: 114.910714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T15:56:36.144087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.132099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-09T15:56:36.144175Z","caller":"traceutil/trace.go:171","msg":"trace[968179370] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6657; }","duration":"112.27779ms","start":"2026-06-09T15:56:36.031879Z","end":"2026-06-09T15:56:36.144156Z","steps":["trace[968179370] 'range keys from in-memory index tree' (duration: 111.946806ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:56:40.730927Z","caller":"traceutil/trace.go:171","msg":"trace[2061056078] transaction","detail":"{read_only:false; response_revision:6681; number_of_response:1; }","duration":"120.123759ms","start":"2026-06-09T15:56:40.610766Z","end":"2026-06-09T15:56:40.73089Z","steps":["trace[2061056078] 'process raft request' (duration: 119.897105ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:56:44.405315Z","caller":"traceutil/trace.go:171","msg":"trace[265351908] transaction","detail":"{read_only:false; response_revision:6707; number_of_response:1; }","duration":"125.887638ms","start":"2026-06-09T15:56:44.279405Z","end":"2026-06-09T15:56:44.405293Z","steps":["trace[265351908] 'process raft request' (duration: 115.96267ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T15:58:52.872137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5547} {"level":"info","ts":"2026-06-09T15:58:52.981908Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5547,"took":"107.597989ms","hash":2334749532,"current-db-size-bytes":53911552,"current-db-size":"54 MB","current-db-size-in-use-bytes":18644992,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-06-09T15:58:52.981981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2334749532,"revision":5547,"compact-revision":2901}