{"level":"warn","ts":"2026-09-19T01:43:23.764662Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T01:43:23.764763Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-19T01:43:23.764825Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T01:43:23.764839Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-09-19T01:43:23.764854Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T01:43:23.765592Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-09-19T01:43:23.765704Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-19T01:43:23.770196Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.226834ms"} {"level":"info","ts":"2026-09-19T01:43:23.78195Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-09-19T01:43:23.782049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T01:43:23.782075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-09-19T01:43:23.782088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T01:43:23.782096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-09-19T01:43:23.782137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-09-19T01:43:23.791394Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T01:43:23.794012Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T01:43:23.797413Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-19T01:43:23.800871Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T01:43:23.801774Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T01:43:23.802065Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T01:43:23.802117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-09-19T01:43:23.802142Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T01:43:23.802275Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-09-19T01:43:23.802287Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T01:43:23.803476Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T01:43:23.803595Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-09-19T01:43:23.803649Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-09-19T01:43:23.803734Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T01:43:23.803766Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T01:43:24.782971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T01:43:24.783019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T01:43:24.783068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-09-19T01:43:24.783087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-09-19T01:43:24.783097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-09-19T01:43:24.783112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-09-19T01:43:24.783124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-09-19T01:43:24.784316Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T01:43:24.78435Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:43:24.784381Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:43:24.784418Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:43:24.784606Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T01:43:24.784637Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T01:43:24.786399Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T01:43:24.7867Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:43:24.786815Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:43:24.786984Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:43:24.796285Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-09-19T01:43:28.746303Z","caller":"traceutil/trace.go:171","msg":"trace[1615611096] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"123.231557ms","start":"2026-09-19T01:43:28.623039Z","end":"2026-09-19T01:43:28.74627Z","steps":["trace[1615611096] 'process raft request' (duration: 80.925358ms)","trace[1615611096] 'compare' (duration: 42.181714ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:43:34.902887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.558412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:43:34.902992Z","caller":"traceutil/trace.go:171","msg":"trace[281099429] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"591.351367ms","start":"2026-09-19T01:43:34.311623Z","end":"2026-09-19T01:43:34.902975Z","steps":["trace[281099429] 'read index received' (duration: 154.199224ms)","trace[281099429] 'applied index is now lower than readState.Index' (duration: 437.151023ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:43:34.903033Z","caller":"traceutil/trace.go:171","msg":"trace[766129393] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"636.138529ms","start":"2026-09-19T01:43:34.266862Z","end":"2026-09-19T01:43:34.903Z","steps":["trace[766129393] 'process raft request' (duration: 199.019887ms)","trace[766129393] 'compare' (duration: 436.456088ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:43:34.903148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.266839Z","time spent":"636.257513ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T01:43:34.903174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.506662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-19T01:43:34.90322Z","caller":"traceutil/trace.go:171","msg":"trace[847968631] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:343; }","duration":"591.629726ms","start":"2026-09-19T01:43:34.311579Z","end":"2026-09-19T01:43:34.903209Z","steps":["trace[847968631] 'agreement among raft nodes before linearized reading' (duration: 591.441579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:34.903248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.311567Z","time spent":"591.671768ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-09-19T01:43:34.903416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.695723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-09-19T01:43:34.903434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.471695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-09-19T01:43:34.903501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.003199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-xxzwjf\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-09-19T01:43:34.9035Z","caller":"traceutil/trace.go:171","msg":"trace[193372105] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:343; }","duration":"541.834238ms","start":"2026-09-19T01:43:34.361645Z","end":"2026-09-19T01:43:34.903479Z","steps":["trace[193372105] 'agreement among raft nodes before linearized reading' (duration: 541.663242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:34.903496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.49493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-19T01:43:34.903538Z","caller":"traceutil/trace.go:171","msg":"trace[1144095140] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-xxzwjf; range_end:; response_count:1; response_revision:343; }","duration":"137.081392ms","start":"2026-09-19T01:43:34.766443Z","end":"2026-09-19T01:43:34.903525Z","steps":["trace[1144095140] 'agreement among raft nodes before linearized reading' (duration: 137.02765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:34.903542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.287238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-09-19T01:43:34.903549Z","caller":"traceutil/trace.go:171","msg":"trace[293556103] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:343; }","duration":"391.588494ms","start":"2026-09-19T01:43:34.511949Z","end":"2026-09-19T01:43:34.903537Z","steps":["trace[293556103] 'agreement among raft nodes before linearized reading' (duration: 391.45132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:34.903548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.361623Z","time spent":"541.91548ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-09-19T01:43:34.903563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.7347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T01:43:34.90357Z","caller":"traceutil/trace.go:171","msg":"trace[1339283844] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:343; }","duration":"492.33185ms","start":"2026-09-19T01:43:34.411228Z","end":"2026-09-19T01:43:34.90356Z","steps":["trace[1339283844] 'agreement among raft nodes before linearized reading' (duration: 492.282988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:34.903578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.511931Z","time spent":"391.638536ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-09-19T01:43:34.903588Z","caller":"traceutil/trace.go:171","msg":"trace[1737495896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:343; }","duration":"391.784692ms","start":"2026-09-19T01:43:34.511795Z","end":"2026-09-19T01:43:34.90358Z","steps":["trace[1737495896] 'agreement among raft nodes before linearized reading' (duration: 391.73501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:34.903594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.411217Z","time spent":"492.370981ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-09-19T01:43:34.903606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.010169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-09-19T01:43:34.903616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.51178Z","time spent":"391.829103ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-19T01:43:34.903633Z","caller":"traceutil/trace.go:171","msg":"trace[57811438] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:343; }","duration":"241.072231ms","start":"2026-09-19T01:43:34.662552Z","end":"2026-09-19T01:43:34.903624Z","steps":["trace[57811438] 'agreement among raft nodes before linearized reading' (duration: 241.011279ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:43:34.903506Z","caller":"traceutil/trace.go:171","msg":"trace[215075711] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:343; }","duration":"441.586179ms","start":"2026-09-19T01:43:34.461904Z","end":"2026-09-19T01:43:34.90349Z","steps":["trace[215075711] 'agreement among raft nodes before linearized reading' (duration: 441.441494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:34.903663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.461885Z","time spent":"441.771405ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-09-19T01:43:34.903685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.473435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-09-19T01:43:34.903727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.142194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-19T01:43:34.903743Z","caller":"traceutil/trace.go:171","msg":"trace[1561096363] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:343; }","duration":"141.557259ms","start":"2026-09-19T01:43:34.762169Z","end":"2026-09-19T01:43:34.903727Z","steps":["trace[1561096363] 'agreement among raft nodes before linearized reading' (duration: 141.486236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:34.90375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.080111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T01:43:34.903751Z","caller":"traceutil/trace.go:171","msg":"trace[1205332366] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:343; }","duration":"292.199515ms","start":"2026-09-19T01:43:34.611542Z","end":"2026-09-19T01:43:34.903741Z","steps":["trace[1205332366] 'agreement among raft nodes before linearized reading' (duration: 292.144863ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:43:34.90377Z","caller":"traceutil/trace.go:171","msg":"trace[587974054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:343; }","duration":"496.135304ms","start":"2026-09-19T01:43:34.407628Z","end":"2026-09-19T01:43:34.903764Z","steps":["trace[587974054] 'agreement among raft nodes before linearized reading' (duration: 496.095233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:34.903785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.407607Z","time spent":"496.173345ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-19T01:43:34.903445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.098477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-19T01:43:34.903811Z","caller":"traceutil/trace.go:171","msg":"trace[798167303] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:343; }","duration":"191.49859ms","start":"2026-09-19T01:43:34.712305Z","end":"2026-09-19T01:43:34.903803Z","steps":["trace[798167303] 'agreement among raft nodes before linearized reading' (duration: 191.082016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:34.903837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.520857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-19T01:43:34.904043Z","caller":"traceutil/trace.go:171","msg":"trace[761647288] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:343; }","duration":"342.087916ms","start":"2026-09-19T01:43:34.561773Z","end":"2026-09-19T01:43:34.903861Z","steps":["trace[761647288] 'agreement among raft nodes before linearized reading' (duration: 341.541028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:34.904087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.561749Z","time spent":"342.326566ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-09-19T01:43:35.213286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.527705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:43:35.213919Z","caller":"traceutil/trace.go:171","msg":"trace[1427458786] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"305.451356ms","start":"2026-09-19T01:43:34.907909Z","end":"2026-09-19T01:43:35.213361Z","steps":["trace[1427458786] 'process raft request' (duration: 146.789736ms)","trace[1427458786] 'compare' (duration: 158.340059ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:43:35.214049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.907895Z","time spent":"306.088479ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:43:35.617895Z","caller":"traceutil/trace.go:171","msg":"trace[1103153482] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"709.387929ms","start":"2026-09-19T01:43:34.908484Z","end":"2026-09-19T01:43:35.617872Z","steps":["trace[1103153482] 'process raft request' (duration: 709.198962ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:43:35.618148Z","caller":"traceutil/trace.go:171","msg":"trace[147892970] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"708.601113ms","start":"2026-09-19T01:43:34.909527Z","end":"2026-09-19T01:43:35.618128Z","steps":["trace[147892970] 'process raft request' (duration: 708.301611ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.618251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.908474Z","time spent":"709.486322ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:43:35.618316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.909506Z","time spent":"708.724997ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:43:35.61869Z","caller":"traceutil/trace.go:171","msg":"trace[26146022] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"707.078578ms","start":"2026-09-19T01:43:34.911595Z","end":"2026-09-19T01:43:35.618673Z","steps":["trace[26146022] 'process raft request' (duration: 706.928653ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:43:35.618803Z","caller":"traceutil/trace.go:171","msg":"trace[2085171070] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"707.42723ms","start":"2026-09-19T01:43:34.911365Z","end":"2026-09-19T01:43:35.618792Z","steps":["trace[2085171070] 'process raft request' (duration: 706.674124ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.618819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.911588Z","time spent":"707.171711ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:43:35.618864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.911356Z","time spent":"707.481052ms","remote":"127.0.0.1:58890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:43:35.619187Z","caller":"traceutil/trace.go:171","msg":"trace[1903524610] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"707.384958ms","start":"2026-09-19T01:43:34.911791Z","end":"2026-09-19T01:43:35.619176Z","steps":["trace[1903524610] 'process raft request' (duration: 706.812789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.619239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.911785Z","time spent":"707.43264ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:43:35.619218Z","caller":"traceutil/trace.go:171","msg":"trace[2038654939] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"704.459706ms","start":"2026-09-19T01:43:34.914738Z","end":"2026-09-19T01:43:35.619197Z","steps":["trace[2038654939] 'process raft request' (duration: 703.963569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.619395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.914732Z","time spent":"704.56749ms","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:43:35.619472Z","caller":"traceutil/trace.go:171","msg":"trace[1329279899] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"705.425569ms","start":"2026-09-19T01:43:34.914037Z","end":"2026-09-19T01:43:35.619463Z","steps":["trace[1329279899] 'process raft request' (duration: 704.609311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.619524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.914025Z","time spent":"705.47008ms","remote":"127.0.0.1:58990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4781,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:43:35.619412Z","caller":"traceutil/trace.go:171","msg":"trace[2123131517] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"704.27395ms","start":"2026-09-19T01:43:34.915119Z","end":"2026-09-19T01:43:35.619393Z","steps":["trace[2123131517] 'process raft request' (duration: 703.960489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.61993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.915114Z","time spent":"704.771958ms","remote":"127.0.0.1:59092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:43:35.619764Z","caller":"traceutil/trace.go:171","msg":"trace[1934256274] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"704.344451ms","start":"2026-09-19T01:43:34.915356Z","end":"2026-09-19T01:43:35.6197Z","steps":["trace[1934256274] 'process raft request' (duration: 703.783981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.620343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.915349Z","time spent":"704.841889ms","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:43:35.621832Z","caller":"traceutil/trace.go:171","msg":"trace[951149632] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:356; }","duration":"710.339451ms","start":"2026-09-19T01:43:34.911471Z","end":"2026-09-19T01:43:35.62181Z","steps":["trace[951149632] 'read index received' (duration: 143.434808ms)","trace[951149632] 'applied index is now lower than readState.Index' (duration: 566.903903ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:43:35.621916Z","caller":"traceutil/trace.go:171","msg":"trace[1783165170] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"705.633426ms","start":"2026-09-19T01:43:34.916264Z","end":"2026-09-19T01:43:35.621898Z","steps":["trace[1783165170] 'process raft request' (duration: 705.489711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.622011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.916259Z","time spent":"705.696208ms","remote":"127.0.0.1:58988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4306,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:43:35.622116Z","caller":"traceutil/trace.go:171","msg":"trace[136314731] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"706.472095ms","start":"2026-09-19T01:43:34.915633Z","end":"2026-09-19T01:43:35.622105Z","steps":["trace[136314731] 'process raft request' (duration: 703.98918ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.622226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.297766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T01:43:35.622297Z","caller":"traceutil/trace.go:171","msg":"trace[926463776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:355; }","duration":"672.394259ms","start":"2026-09-19T01:43:34.949892Z","end":"2026-09-19T01:43:35.622287Z","steps":["trace[926463776] 'agreement among raft nodes before linearized reading' (duration: 672.297376ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.62233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.949873Z","time spent":"672.449253ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T01:43:35.622418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.915628Z","time spent":"706.61285ms","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:43:35.622697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.353177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-19T01:43:35.622738Z","caller":"traceutil/trace.go:171","msg":"trace[1098308580] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:355; }","duration":"660.4171ms","start":"2026-09-19T01:43:34.962313Z","end":"2026-09-19T01:43:35.622731Z","steps":["trace[1098308580] 'agreement among raft nodes before linearized reading' (duration: 660.351357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.622757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.962302Z","time spent":"660.45038ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-09-19T01:43:35.622912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.084785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-19T01:43:35.62294Z","caller":"traceutil/trace.go:171","msg":"trace[1039124497] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:355; }","duration":"610.144297ms","start":"2026-09-19T01:43:35.012786Z","end":"2026-09-19T01:43:35.622931Z","steps":["trace[1039124497] 'agreement among raft nodes before linearized reading' (duration: 610.075805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.622958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:35.012766Z","time spent":"610.186779ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-09-19T01:43:35.624065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.639011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-19T01:43:35.624107Z","caller":"traceutil/trace.go:171","msg":"trace[1536724596] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:355; }","duration":"511.732293ms","start":"2026-09-19T01:43:35.112369Z","end":"2026-09-19T01:43:35.624101Z","steps":["trace[1536724596] 'agreement among raft nodes before linearized reading' (duration: 510.661086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.624123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:35.112352Z","time spent":"511.765995ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-09-19T01:43:35.622513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.145608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-09-19T01:43:35.621967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.477036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-19T01:43:35.625235Z","caller":"traceutil/trace.go:171","msg":"trace[689781492] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:355; }","duration":"713.768812ms","start":"2026-09-19T01:43:34.911457Z","end":"2026-09-19T01:43:35.625226Z","steps":["trace[689781492] 'agreement among raft nodes before linearized reading' (duration: 710.465336ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.625256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:34.911453Z","time spent":"713.795032ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-09-19T01:43:35.625201Z","caller":"traceutil/trace.go:171","msg":"trace[1900847206] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:355; }","duration":"562.887553ms","start":"2026-09-19T01:43:35.062261Z","end":"2026-09-19T01:43:35.625148Z","steps":["trace[1900847206] 'agreement among raft nodes before linearized reading' (duration: 560.138027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:43:35.6267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:43:35.06223Z","time spent":"564.445208ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-09-19T01:44:04.884472Z","caller":"traceutil/trace.go:171","msg":"trace[777194200] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"230.047663ms","start":"2026-09-19T01:44:04.654404Z","end":"2026-09-19T01:44:04.884451Z","steps":["trace[777194200] 'process raft request' (duration: 229.920599ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:44:04.894035Z","caller":"traceutil/trace.go:171","msg":"trace[365241795] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"237.322134ms","start":"2026-09-19T01:44:04.65668Z","end":"2026-09-19T01:44:04.894002Z","steps":["trace[365241795] 'process raft request' (duration: 236.968063ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:44:04.894382Z","caller":"traceutil/trace.go:171","msg":"trace[704609735] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"236.616154ms","start":"2026-09-19T01:44:04.657749Z","end":"2026-09-19T01:44:04.894365Z","steps":["trace[704609735] 'process raft request' (duration: 236.083988ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:44:04.894765Z","caller":"traceutil/trace.go:171","msg":"trace[453650045] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"225.301391ms","start":"2026-09-19T01:44:04.669446Z","end":"2026-09-19T01:44:04.894747Z","steps":["trace[453650045] 'process raft request' (duration: 224.799767ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:44:04.895601Z","caller":"traceutil/trace.go:171","msg":"trace[1813192559] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"225.98627ms","start":"2026-09-19T01:44:04.669582Z","end":"2026-09-19T01:44:04.895568Z","steps":["trace[1813192559] 'process raft request' (duration: 225.032233ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:44:04.89574Z","caller":"traceutil/trace.go:171","msg":"trace[1655924235] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"222.829112ms","start":"2026-09-19T01:44:04.672863Z","end":"2026-09-19T01:44:04.895692Z","steps":["trace[1655924235] 'process raft request' (duration: 222.495673ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:44:04.895745Z","caller":"traceutil/trace.go:171","msg":"trace[105579648] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"224.423236ms","start":"2026-09-19T01:44:04.671229Z","end":"2026-09-19T01:44:04.895652Z","steps":["trace[105579648] 'process raft request' (duration: 224.036946ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:44:04.895963Z","caller":"traceutil/trace.go:171","msg":"trace[26007211] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"222.539114ms","start":"2026-09-19T01:44:04.673408Z","end":"2026-09-19T01:44:04.895947Z","steps":["trace[26007211] 'process raft request' (duration: 222.058271ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:44:04.896314Z","caller":"traceutil/trace.go:171","msg":"trace[1231902212] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"211.113409ms","start":"2026-09-19T01:44:04.685187Z","end":"2026-09-19T01:44:04.8963Z","steps":["trace[1231902212] 'process raft request' (duration: 210.390249ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:44:04.896409Z","caller":"traceutil/trace.go:171","msg":"trace[1179141580] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"201.509483ms","start":"2026-09-19T01:44:04.694872Z","end":"2026-09-19T01:44:04.896382Z","steps":["trace[1179141580] 'process raft request' (duration: 201.358049ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:44:04.896806Z","caller":"traceutil/trace.go:171","msg":"trace[420797412] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"160.009896ms","start":"2026-09-19T01:44:04.73678Z","end":"2026-09-19T01:44:04.89679Z","steps":["trace[420797412] 'process raft request' (duration: 159.528982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:44:11.336461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.415517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:44:11.33693Z","caller":"traceutil/trace.go:171","msg":"trace[1214686261] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"357.586284ms","start":"2026-09-19T01:44:10.979328Z","end":"2026-09-19T01:44:11.336914Z","steps":["trace[1214686261] 'process raft request' (duration: 41.572331ms)","trace[1214686261] 'compare' (duration: 315.207472ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:44:11.337022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:44:10.979282Z","time spent":"357.685516ms","remote":"127.0.0.1:59306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T01:44:11.337236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.054752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:44:11.337316Z","caller":"traceutil/trace.go:171","msg":"trace[1598436269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"264.185326ms","start":"2026-09-19T01:44:11.073111Z","end":"2026-09-19T01:44:11.337297Z","steps":["trace[1598436269] 'agreement among raft nodes before linearized reading' (duration: 263.992011ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:44:11.336864Z","caller":"traceutil/trace.go:171","msg":"trace[133224658] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"263.681233ms","start":"2026-09-19T01:44:11.073165Z","end":"2026-09-19T01:44:11.336847Z","steps":["trace[133224658] 'read index received' (duration: 54.852µs)","trace[133224658] 'applied index is now lower than readState.Index' (duration: 263.625021ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:44:12.212686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.828761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:44:12.213202Z","caller":"traceutil/trace.go:171","msg":"trace[329732928] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"590.991527ms","start":"2026-09-19T01:44:11.622151Z","end":"2026-09-19T01:44:12.213143Z","steps":["trace[329732928] 'read index received' (duration: 31.261µs)","trace[329732928] 'applied index is now lower than readState.Index' (duration: 590.958446ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:44:12.213218Z","caller":"traceutil/trace.go:171","msg":"trace[368132950] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"759.309495ms","start":"2026-09-19T01:44:11.453867Z","end":"2026-09-19T01:44:12.213176Z","steps":["trace[368132950] 'process raft request' (duration: 15.889789ms)","trace[368132950] 'compare' (duration: 742.627865ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:44:12.21342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.257264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lndmj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-19T01:44:12.213787Z","caller":"traceutil/trace.go:171","msg":"trace[1981823674] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lndmj; range_end:; response_count:1; response_revision:646; }","duration":"591.657534ms","start":"2026-09-19T01:44:11.62211Z","end":"2026-09-19T01:44:12.213768Z","steps":["trace[1981823674] 'agreement among raft nodes before linearized reading' (duration: 591.154092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:44:12.213865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:44:11.622091Z","time spent":"591.762318ms","remote":"127.0.0.1:58990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-lndmj\" "} {"level":"warn","ts":"2026-09-19T01:44:12.214374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:44:11.453844Z","time spent":"759.804778ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:44:12.260282Z","caller":"traceutil/trace.go:171","msg":"trace[824721859] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"516.938658ms","start":"2026-09-19T01:44:11.743314Z","end":"2026-09-19T01:44:12.260253Z","steps":["trace[824721859] 'process raft request' (duration: 515.552651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:44:12.260502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.734994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:44:12.260559Z","caller":"traceutil/trace.go:171","msg":"trace[1216702646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"188.856877ms","start":"2026-09-19T01:44:12.071685Z","end":"2026-09-19T01:44:12.260542Z","steps":["trace[1216702646] 'agreement among raft nodes before linearized reading' (duration: 188.781955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:44:12.260635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.206726ms","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-09-19T01:44:12.260732Z","caller":"traceutil/trace.go:171","msg":"trace[914567000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"118.3426ms","start":"2026-09-19T01:44:12.142367Z","end":"2026-09-19T01:44:12.26071Z","steps":["trace[914567000] 'agreement among raft nodes before linearized reading' (duration: 118.149546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:44:12.260941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.203805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T01:44:12.261Z","caller":"traceutil/trace.go:171","msg":"trace[391937833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"307.318879ms","start":"2026-09-19T01:44:11.95367Z","end":"2026-09-19T01:44:12.260989Z","steps":["trace[391937833] 'agreement among raft nodes before linearized reading' (duration: 307.200036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:44:12.261029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:44:11.953645Z","time spent":"307.37583ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-19T01:44:12.26109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.341985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T01:44:12.262278Z","caller":"traceutil/trace.go:171","msg":"trace[124799818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"404.294066ms","start":"2026-09-19T01:44:11.857968Z","end":"2026-09-19T01:44:12.262262Z","steps":["trace[124799818] 'agreement among raft nodes before linearized reading' (duration: 402.271503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:44:12.262335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:44:11.857946Z","time spent":"404.366168ms","remote":"127.0.0.1:59088","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-09-19T01:44:12.261557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.117868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T01:44:12.262533Z","caller":"traceutil/trace.go:171","msg":"trace[1115078773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"301.649728ms","start":"2026-09-19T01:44:11.960817Z","end":"2026-09-19T01:44:12.262467Z","steps":["trace[1115078773] 'agreement among raft nodes before linearized reading' (duration: 300.045796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:44:12.262645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:44:11.960797Z","time spent":"301.810963ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T01:44:12.261923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:44:11.743293Z","time spent":"517.05193ms","remote":"127.0.0.1:58898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:44:29.146839Z","caller":"traceutil/trace.go:171","msg":"trace[2057931952] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"412.648211ms","start":"2026-09-19T01:44:28.734171Z","end":"2026-09-19T01:44:29.146819Z","steps":["trace[2057931952] 'process raft request' (duration: 412.309293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:44:29.146964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:44:28.734152Z","time spent":"412.747994ms","remote":"127.0.0.1:58990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:45:18.627908Z","caller":"traceutil/trace.go:171","msg":"trace[1496894267] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"147.234552ms","start":"2026-09-19T01:45:18.480633Z","end":"2026-09-19T01:45:18.627868Z","steps":["trace[1496894267] 'process raft request' (duration: 146.984798ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:45:51.808832Z","caller":"traceutil/trace.go:171","msg":"trace[1644685403] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"113.382997ms","start":"2026-09-19T01:45:51.69542Z","end":"2026-09-19T01:45:51.808803Z","steps":["trace[1644685403] 'read index received' (duration: 19.614216ms)","trace[1644685403] 'applied index is now lower than readState.Index' (duration: 93.767481ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:45:51.808912Z","caller":"traceutil/trace.go:171","msg":"trace[1819319444] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"138.327057ms","start":"2026-09-19T01:45:51.670554Z","end":"2026-09-19T01:45:51.808881Z","steps":["trace[1819319444] 'process raft request' (duration: 44.492554ms)","trace[1819319444] 'compare' (duration: 93.530267ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:45:51.809102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.660242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33fa8af3-1b8a-481b-bdac-ea6ea096f0c6\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-19T01:45:51.809151Z","caller":"traceutil/trace.go:171","msg":"trace[620133361] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-33fa8af3-1b8a-481b-bdac-ea6ea096f0c6; range_end:; response_count:1; response_revision:1917; }","duration":"113.739603ms","start":"2026-09-19T01:45:51.695395Z","end":"2026-09-19T01:45:51.809135Z","steps":["trace[620133361] 'agreement among raft nodes before linearized reading' (duration: 113.540369ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:45:52.338656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.977329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33fa8af3-1b8a-481b-bdac-ea6ea096f0c6\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-19T01:45:52.338772Z","caller":"traceutil/trace.go:171","msg":"trace[1208079197] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-33fa8af3-1b8a-481b-bdac-ea6ea096f0c6; range_end:; response_count:1; response_revision:1917; }","duration":"223.148252ms","start":"2026-09-19T01:45:52.115603Z","end":"2026-09-19T01:45:52.338751Z","steps":["trace[1208079197] 'range keys from in-memory index tree' (duration: 222.620114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:45:52.338883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.36393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:45:52.338968Z","caller":"traceutil/trace.go:171","msg":"trace[1466693069] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1917; }","duration":"417.475432ms","start":"2026-09-19T01:45:51.921475Z","end":"2026-09-19T01:45:52.338951Z","steps":["trace[1466693069] 'count revisions from in-memory index tree' (duration: 417.300778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:45:52.339029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:45:51.921454Z","time spent":"417.545494ms","remote":"127.0.0.1:47770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-09-19T01:45:52.339361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.436631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:45:52.339432Z","caller":"traceutil/trace.go:171","msg":"trace[217386537] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1917; }","duration":"259.683416ms","start":"2026-09-19T01:45:52.079723Z","end":"2026-09-19T01:45:52.339407Z","steps":["trace[217386537] 'count revisions from in-memory index tree' (duration: 259.33073ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:45:52.398202Z","caller":"traceutil/trace.go:171","msg":"trace[1615526155] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1992; }","duration":"167.339702ms","start":"2026-09-19T01:45:52.230839Z","end":"2026-09-19T01:45:52.398179Z","steps":["trace[1615526155] 'read index received' (duration: 167.185619ms)","trace[1615526155] 'applied index is now lower than readState.Index' (duration: 152.713µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:45:52.398287Z","caller":"traceutil/trace.go:171","msg":"trace[1416503137] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"180.957951ms","start":"2026-09-19T01:45:52.217288Z","end":"2026-09-19T01:45:52.398246Z","steps":["trace[1416503137] 'process raft request' (duration: 180.739947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:45:52.398419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.550155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-19T01:45:52.398474Z","caller":"traceutil/trace.go:171","msg":"trace[681573091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1918; }","duration":"167.664668ms","start":"2026-09-19T01:45:52.230796Z","end":"2026-09-19T01:45:52.39846Z","steps":["trace[681573091] 'agreement among raft nodes before linearized reading' (duration: 167.469494ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:45:52.613163Z","caller":"traceutil/trace.go:171","msg":"trace[927872765] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"266.81083ms","start":"2026-09-19T01:45:52.346327Z","end":"2026-09-19T01:45:52.613138Z","steps":["trace[927872765] 'process raft request' (duration: 255.467771ms)","trace[927872765] 'compare' (duration: 11.154206ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:45:52.656278Z","caller":"traceutil/trace.go:171","msg":"trace[1587790924] linearizableReadLoop","detail":"{readStateIndex:1995; appliedIndex:1993; }","duration":"181.795566ms","start":"2026-09-19T01:45:52.474457Z","end":"2026-09-19T01:45:52.656252Z","steps":["trace[1587790924] 'read index received' (duration: 127.261887ms)","trace[1587790924] 'applied index is now lower than readState.Index' (duration: 54.526179ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:45:52.656325Z","caller":"traceutil/trace.go:171","msg":"trace[541420682] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"148.894687ms","start":"2026-09-19T01:45:52.507395Z","end":"2026-09-19T01:45:52.65629Z","steps":["trace[541420682] 'process raft request' (duration: 148.809925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:45:52.65646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.976749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:45:52.6565Z","caller":"traceutil/trace.go:171","msg":"trace[1164378541] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1921; }","duration":"182.03884ms","start":"2026-09-19T01:45:52.474449Z","end":"2026-09-19T01:45:52.656488Z","steps":["trace[1164378541] 'agreement among raft nodes before linearized reading' (duration: 181.894578ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:45:52.656877Z","caller":"traceutil/trace.go:171","msg":"trace[247536134] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"253.952315ms","start":"2026-09-19T01:45:52.4029Z","end":"2026-09-19T01:45:52.656852Z","steps":["trace[247536134] 'process raft request' (duration: 253.157231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:45:52.903248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.842239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:45:52.903438Z","caller":"traceutil/trace.go:171","msg":"trace[1077155891] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1996; }","duration":"110.150776ms","start":"2026-09-19T01:45:52.793269Z","end":"2026-09-19T01:45:52.90342Z","steps":["trace[1077155891] 'read index received' (duration: 49.731µs)","trace[1077155891] 'applied index is now lower than readState.Index' (duration: 110.098665ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:45:52.903555Z","caller":"traceutil/trace.go:171","msg":"trace[1531391538] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"291.996593ms","start":"2026-09-19T01:45:52.611519Z","end":"2026-09-19T01:45:52.903516Z","steps":["trace[1531391538] 'process raft request' (duration: 110.785387ms)","trace[1531391538] 'compare' (duration: 180.538214ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:45:52.903634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.298139ms","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":"warn","ts":"2026-09-19T01:45:52.903791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.108692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T01:45:52.903817Z","caller":"traceutil/trace.go:171","msg":"trace[97214466] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1922; }","duration":"110.540593ms","start":"2026-09-19T01:45:52.793203Z","end":"2026-09-19T01:45:52.903744Z","steps":["trace[97214466] 'agreement among raft nodes before linearized reading' (duration: 110.315329ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:45:52.903855Z","caller":"traceutil/trace.go:171","msg":"trace[1905276553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1922; }","duration":"103.209675ms","start":"2026-09-19T01:45:52.800634Z","end":"2026-09-19T01:45:52.903844Z","steps":["trace[1905276553] 'agreement among raft nodes before linearized reading' (duration: 102.92364ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:45:58.601302Z","caller":"traceutil/trace.go:171","msg":"trace[782231392] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"177.740218ms","start":"2026-09-19T01:45:58.423539Z","end":"2026-09-19T01:45:58.601279Z","steps":["trace[782231392] 'process raft request' (duration: 177.571585ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:46:05.836796Z","caller":"traceutil/trace.go:171","msg":"trace[321513619] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"165.659113ms","start":"2026-09-19T01:46:05.671114Z","end":"2026-09-19T01:46:05.836774Z","steps":["trace[321513619] 'process raft request' (duration: 165.51794ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:46:06.050146Z","caller":"traceutil/trace.go:171","msg":"trace[1560161563] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"206.903589ms","start":"2026-09-19T01:46:05.843216Z","end":"2026-09-19T01:46:06.05012Z","steps":["trace[1560161563] 'process raft request' (duration: 139.811758ms)","trace[1560161563] 'compare' (duration: 66.902287ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:46:06.086857Z","caller":"traceutil/trace.go:171","msg":"trace[1826152946] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"243.477666ms","start":"2026-09-19T01:46:05.843357Z","end":"2026-09-19T01:46:06.086834Z","steps":["trace[1826152946] 'process raft request' (duration: 243.123579ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:46:06.087411Z","caller":"traceutil/trace.go:171","msg":"trace[466741837] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"224.306096ms","start":"2026-09-19T01:46:05.86308Z","end":"2026-09-19T01:46:06.087386Z","steps":["trace[466741837] 'process raft request' (duration: 224.157574ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:46:06.087626Z","caller":"traceutil/trace.go:171","msg":"trace[1905919637] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"243.986535ms","start":"2026-09-19T01:46:05.843626Z","end":"2026-09-19T01:46:06.087612Z","steps":["trace[1905919637] 'process raft request' (duration: 243.035548ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:46:06.087532Z","caller":"traceutil/trace.go:171","msg":"trace[350121164] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"241.026884ms","start":"2026-09-19T01:46:05.846459Z","end":"2026-09-19T01:46:06.087486Z","steps":["trace[350121164] 'process raft request' (duration: 240.325181ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:46:32.381707Z","caller":"traceutil/trace.go:171","msg":"trace[440999889] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"101.992865ms","start":"2026-09-19T01:46:32.279671Z","end":"2026-09-19T01:46:32.381664Z","steps":["trace[440999889] 'read index received' (duration: 101.63347ms)","trace[440999889] 'applied index is now lower than readState.Index' (duration: 357.975µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:46:32.381827Z","caller":"traceutil/trace.go:171","msg":"trace[1799507435] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"197.237299ms","start":"2026-09-19T01:46:32.184551Z","end":"2026-09-19T01:46:32.381789Z","steps":["trace[1799507435] 'process raft request' (duration: 196.820783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:46:32.382096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.423583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T01:46:32.382201Z","caller":"traceutil/trace.go:171","msg":"trace[1367366532] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2186; }","duration":"102.567675ms","start":"2026-09-19T01:46:32.279581Z","end":"2026-09-19T01:46:32.382149Z","steps":["trace[1367366532] 'agreement among raft nodes before linearized reading' (duration: 102.25307ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:46:33.076282Z","caller":"traceutil/trace.go:171","msg":"trace[1797479677] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"102.046215ms","start":"2026-09-19T01:46:32.97421Z","end":"2026-09-19T01:46:33.076256Z","steps":["trace[1797479677] 'process raft request' (duration: 101.822362ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:46:33.6418Z","caller":"traceutil/trace.go:171","msg":"trace[361378086] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"416.374124ms","start":"2026-09-19T01:46:33.2254Z","end":"2026-09-19T01:46:33.641774Z","steps":["trace[361378086] 'process raft request' (duration: 416.297193ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:46:33.641847Z","caller":"traceutil/trace.go:171","msg":"trace[1518600038] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"416.600478ms","start":"2026-09-19T01:46:33.225227Z","end":"2026-09-19T01:46:33.641828Z","steps":["trace[1518600038] 'process raft request' (duration: 416.325014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:46:33.641937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:46:33.225377Z","time spent":"416.492396ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:46:33.64195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:46:33.225217Z","time spent":"416.669419ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:46:33.642054Z","caller":"traceutil/trace.go:171","msg":"trace[903427136] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"415.245276ms","start":"2026-09-19T01:46:33.226799Z","end":"2026-09-19T01:46:33.642045Z","steps":["trace[903427136] 'process raft request' (duration: 414.940151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:46:33.6421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:46:33.226785Z","time spent":"415.290896ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:46:33.672473Z","caller":"traceutil/trace.go:171","msg":"trace[277200356] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"396.094528ms","start":"2026-09-19T01:46:33.276339Z","end":"2026-09-19T01:46:33.672433Z","steps":["trace[277200356] 'process raft request' (duration: 395.908235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:46:33.672727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:46:33.276314Z","time spent":"396.316522ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:46:33.998035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.708686ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-19T01:46:33.999086Z","caller":"traceutil/trace.go:171","msg":"trace[1749111343] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2275; }","duration":"609.40214ms","start":"2026-09-19T01:46:33.38966Z","end":"2026-09-19T01:46:33.999062Z","steps":["trace[1749111343] 'read index received' (duration: 251.879649ms)","trace[1749111343] 'applied index is now lower than readState.Index' (duration: 357.519851ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:46:33.999396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.724895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T01:46:33.999459Z","caller":"traceutil/trace.go:171","msg":"trace[916413746] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2194; }","duration":"609.832206ms","start":"2026-09-19T01:46:33.389608Z","end":"2026-09-19T01:46:33.99944Z","steps":["trace[916413746] 'agreement among raft nodes before linearized reading' (duration: 609.585902ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:46:33.999503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:46:33.389587Z","time spent":"609.903138ms","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-19T01:46:34.025406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.232246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:46:34.025509Z","caller":"traceutil/trace.go:171","msg":"trace[1994336155] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2195; }","duration":"598.382488ms","start":"2026-09-19T01:46:33.427104Z","end":"2026-09-19T01:46:34.025486Z","steps":["trace[1994336155] 'agreement among raft nodes before linearized reading' (duration: 598.195745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:46:34.025555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:46:33.427088Z","time spent":"598.453809ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-09-19T01:46:34.025969Z","caller":"traceutil/trace.go:171","msg":"trace[1741817961] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"118.437845ms","start":"2026-09-19T01:46:33.906927Z","end":"2026-09-19T01:46:34.025364Z","steps":["trace[1741817961] 'process raft request' (duration: 118.10672ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:47:51.620052Z","caller":"traceutil/trace.go:171","msg":"trace[86987481] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"105.549691ms","start":"2026-09-19T01:47:51.514474Z","end":"2026-09-19T01:47:51.620024Z","steps":["trace[86987481] 'process raft request' (duration: 105.396484ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:47:52.148671Z","caller":"traceutil/trace.go:171","msg":"trace[1816129031] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"103.38247ms","start":"2026-09-19T01:47:52.045243Z","end":"2026-09-19T01:47:52.148625Z","steps":["trace[1816129031] 'process raft request' (duration: 78.785756ms)","trace[1816129031] 'compare' (duration: 24.260339ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:48:04.199342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.189177ms","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-09-19T01:48:04.20054Z","caller":"traceutil/trace.go:171","msg":"trace[85507944] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2785; }","duration":"276.443877ms","start":"2026-09-19T01:48:03.924071Z","end":"2026-09-19T01:48:04.200515Z","steps":["trace[85507944] 'range keys from in-memory index tree' (duration: 274.964978ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:48:04.199783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.158639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:48:04.200903Z","caller":"traceutil/trace.go:171","msg":"trace[1308188994] linearizableReadLoop","detail":"{readStateIndex:2892; appliedIndex:2891; }","duration":"145.823783ms","start":"2026-09-19T01:48:04.055033Z","end":"2026-09-19T01:48:04.200856Z","steps":["trace[1308188994] 'read index received' (duration: 20.435724ms)","trace[1308188994] 'applied index is now lower than readState.Index' (duration: 125.379519ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:48:04.201115Z","caller":"traceutil/trace.go:171","msg":"trace[337097712] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"176.346206ms","start":"2026-09-19T01:48:04.024753Z","end":"2026-09-19T01:48:04.201099Z","steps":["trace[337097712] 'process raft request' (duration: 50.78702ms)","trace[337097712] 'compare' (duration: 123.491763ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:48:04.201244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.770905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:48:04.201345Z","caller":"traceutil/trace.go:171","msg":"trace[153811375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2786; }","duration":"127.931771ms","start":"2026-09-19T01:48:04.073378Z","end":"2026-09-19T01:48:04.20131Z","steps":["trace[153811375] 'agreement among raft nodes before linearized reading' (duration: 127.773565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:48:04.201518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.47579ms","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-09-19T01:48:04.201563Z","caller":"traceutil/trace.go:171","msg":"trace[791203884] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2786; }","duration":"146.609365ms","start":"2026-09-19T01:48:04.054942Z","end":"2026-09-19T01:48:04.201551Z","steps":["trace[791203884] 'agreement among raft nodes before linearized reading' (duration: 146.51011ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:48:04.376886Z","caller":"traceutil/trace.go:171","msg":"trace[1785711474] linearizableReadLoop","detail":"{readStateIndex:2893; appliedIndex:2892; }","duration":"166.751908ms","start":"2026-09-19T01:48:04.210099Z","end":"2026-09-19T01:48:04.376851Z","steps":["trace[1785711474] 'read index received' (duration: 156.512954ms)","trace[1785711474] 'applied index is now lower than readState.Index' (duration: 10.237564ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:48:04.377528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.521818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T01:48:04.377565Z","caller":"traceutil/trace.go:171","msg":"trace[1603278615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2787; }","duration":"165.600471ms","start":"2026-09-19T01:48:04.211952Z","end":"2026-09-19T01:48:04.377553Z","steps":["trace[1603278615] 'agreement among raft nodes before linearized reading' (duration: 165.425554ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:48:04.377731Z","caller":"traceutil/trace.go:171","msg":"trace[729282648] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"169.398074ms","start":"2026-09-19T01:48:04.208322Z","end":"2026-09-19T01:48:04.37772Z","steps":["trace[729282648] 'process raft request' (duration: 158.312787ms)","trace[729282648] 'compare' (duration: 10.075117ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:48:04.377864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.762468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-09-19T01:48:04.377879Z","caller":"traceutil/trace.go:171","msg":"trace[1674077325] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2787; }","duration":"167.800119ms","start":"2026-09-19T01:48:04.210074Z","end":"2026-09-19T01:48:04.377874Z","steps":["trace[1674077325] 'agreement among raft nodes before linearized reading' (duration: 167.737937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:48:04.378025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.384478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T01:48:04.378042Z","caller":"traceutil/trace.go:171","msg":"trace[536251747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2787; }","duration":"152.444581ms","start":"2026-09-19T01:48:04.225592Z","end":"2026-09-19T01:48:04.378036Z","steps":["trace[536251747] 'agreement among raft nodes before linearized reading' (duration: 152.374328ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:48:44.309401Z","caller":"traceutil/trace.go:171","msg":"trace[202221236] transaction","detail":"{read_only:false; response_revision:2999; number_of_response:1; }","duration":"126.912301ms","start":"2026-09-19T01:48:44.182461Z","end":"2026-09-19T01:48:44.309373Z","steps":["trace[202221236] 'process raft request' (duration: 126.731515ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:40.196611Z","caller":"traceutil/trace.go:171","msg":"trace[606716948] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"255.871591ms","start":"2026-09-19T01:52:39.94072Z","end":"2026-09-19T01:52:40.196592Z","steps":["trace[606716948] 'process raft request' (duration: 221.758929ms)","trace[606716948] 'compare' (duration: 34.03349ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:52:40.349184Z","caller":"traceutil/trace.go:171","msg":"trace[1367801121] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"183.835554ms","start":"2026-09-19T01:52:40.165315Z","end":"2026-09-19T01:52:40.34915Z","steps":["trace[1367801121] 'process raft request' (duration: 146.440213ms)","trace[1367801121] 'compare' (duration: 37.258528ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:52:40.498276Z","caller":"traceutil/trace.go:171","msg":"trace[962658451] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"101.907557ms","start":"2026-09-19T01:52:40.396342Z","end":"2026-09-19T01:52:40.49825Z","steps":["trace[962658451] 'process raft request' (duration: 75.50769ms)","trace[962658451] 'compare' (duration: 26.230803ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:52:40.498486Z","caller":"traceutil/trace.go:171","msg":"trace[1516565207] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"101.707272ms","start":"2026-09-19T01:52:40.396761Z","end":"2026-09-19T01:52:40.498469Z","steps":["trace[1516565207] 'process raft request' (duration: 101.421737ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:40.498905Z","caller":"traceutil/trace.go:171","msg":"trace[28824414] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"102.06668ms","start":"2026-09-19T01:52:40.396824Z","end":"2026-09-19T01:52:40.49889Z","steps":["trace[28824414] 'process raft request' (duration: 101.58696ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:42.649853Z","caller":"traceutil/trace.go:171","msg":"trace[1370003499] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5039; }","duration":"124.866995ms","start":"2026-09-19T01:52:42.524961Z","end":"2026-09-19T01:52:42.649828Z","steps":["trace[1370003499] 'process raft request' (duration: 124.732251ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:53.461675Z","caller":"traceutil/trace.go:171","msg":"trace[671928657] linearizableReadLoop","detail":"{readStateIndex:5365; appliedIndex:5364; }","duration":"212.139081ms","start":"2026-09-19T01:52:53.249512Z","end":"2026-09-19T01:52:53.461651Z","steps":["trace[671928657] 'read index received' (duration: 211.891506ms)","trace[671928657] 'applied index is now lower than readState.Index' (duration: 246.695µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:52:53.461744Z","caller":"traceutil/trace.go:171","msg":"trace[2022406639] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"311.659233ms","start":"2026-09-19T01:52:53.150061Z","end":"2026-09-19T01:52:53.46172Z","steps":["trace[2022406639] 'process raft request' (duration: 311.406127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:52:53.461879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.341616ms","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-09-19T01:52:53.462107Z","caller":"traceutil/trace.go:171","msg":"trace[644561071] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5167; }","duration":"212.455298ms","start":"2026-09-19T01:52:53.249467Z","end":"2026-09-19T01:52:53.461922Z","steps":["trace[644561071] 'agreement among raft nodes before linearized reading' (duration: 212.345776ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:52:53.46208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:52:53.150035Z","time spent":"311.775956ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:52:53.568555Z","caller":"traceutil/trace.go:171","msg":"trace[918333359] linearizableReadLoop","detail":"{readStateIndex:5366; appliedIndex:5365; }","duration":"106.765556ms","start":"2026-09-19T01:52:53.461764Z","end":"2026-09-19T01:52:53.56853Z","steps":["trace[918333359] 'read index received' (duration: 74.972571ms)","trace[918333359] 'applied index is now lower than readState.Index' (duration: 31.791665ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:52:53.568754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.886016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-19T01:52:53.568803Z","caller":"traceutil/trace.go:171","msg":"trace[78620090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5168; }","duration":"202.966987ms","start":"2026-09-19T01:52:53.365807Z","end":"2026-09-19T01:52:53.568774Z","steps":["trace[78620090] 'agreement among raft nodes before linearized reading' (duration: 202.811433ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:53.56907Z","caller":"traceutil/trace.go:171","msg":"trace[1052194265] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"228.353365ms","start":"2026-09-19T01:52:53.340702Z","end":"2026-09-19T01:52:53.569055Z","steps":["trace[1052194265] 'process raft request' (duration: 196.018949ms)","trace[1052194265] 'compare' (duration: 31.661493ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:52:53.569276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.284947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-19T01:52:53.569303Z","caller":"traceutil/trace.go:171","msg":"trace[1227554014] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5168; }","duration":"132.348078ms","start":"2026-09-19T01:52:53.436946Z","end":"2026-09-19T01:52:53.569294Z","steps":["trace[1227554014] 'agreement among raft nodes before linearized reading' (duration: 132.270987ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:53.660177Z","caller":"traceutil/trace.go:171","msg":"trace[1188530435] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"142.79324ms","start":"2026-09-19T01:52:53.517348Z","end":"2026-09-19T01:52:53.660142Z","steps":["trace[1188530435] 'process raft request' (duration: 142.559155ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:53.846044Z","caller":"traceutil/trace.go:171","msg":"trace[1726316199] transaction","detail":"{read_only:false; response_revision:5172; number_of_response:1; }","duration":"172.707295ms","start":"2026-09-19T01:52:53.673312Z","end":"2026-09-19T01:52:53.84602Z","steps":["trace[1726316199] 'process raft request' (duration: 90.95228ms)","trace[1726316199] 'compare' (duration: 81.571241ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:52:53.899733Z","caller":"traceutil/trace.go:171","msg":"trace[1596721499] transaction","detail":"{read_only:false; response_revision:5173; number_of_response:1; }","duration":"226.170769ms","start":"2026-09-19T01:52:53.673519Z","end":"2026-09-19T01:52:53.899689Z","steps":["trace[1596721499] 'process raft request' (duration: 226.039326ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:53.926387Z","caller":"traceutil/trace.go:171","msg":"trace[502561086] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"102.973235ms","start":"2026-09-19T01:52:53.823372Z","end":"2026-09-19T01:52:53.926346Z","steps":["trace[502561086] 'process raft request' (duration: 102.841492ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:54.023581Z","caller":"traceutil/trace.go:171","msg":"trace[1263736188] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"116.88112ms","start":"2026-09-19T01:52:53.906681Z","end":"2026-09-19T01:52:54.023562Z","steps":["trace[1263736188] 'process raft request' (duration: 116.768958ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:54.023694Z","caller":"traceutil/trace.go:171","msg":"trace[114378857] linearizableReadLoop","detail":"{readStateIndex:5375; appliedIndex:5372; }","duration":"116.41975ms","start":"2026-09-19T01:52:53.907257Z","end":"2026-09-19T01:52:54.023676Z","steps":["trace[114378857] 'read index received' (duration: 19.035594ms)","trace[114378857] 'applied index is now lower than readState.Index' (duration: 97.382436ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:52:54.023783Z","caller":"traceutil/trace.go:171","msg":"trace[605936690] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"111.313501ms","start":"2026-09-19T01:52:53.912443Z","end":"2026-09-19T01:52:54.023757Z","steps":["trace[605936690] 'process raft request' (duration: 111.063536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:52:54.023871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.606603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-19T01:52:54.023911Z","caller":"traceutil/trace.go:171","msg":"trace[1310152276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5177; }","duration":"116.687665ms","start":"2026-09-19T01:52:53.907213Z","end":"2026-09-19T01:52:54.023901Z","steps":["trace[1310152276] 'agreement among raft nodes before linearized reading' (duration: 116.555623ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:52:54.024135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.505778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-09-19T01:52:54.024183Z","caller":"traceutil/trace.go:171","msg":"trace[645487068] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5177; }","duration":"105.61508ms","start":"2026-09-19T01:52:53.918551Z","end":"2026-09-19T01:52:54.024166Z","steps":["trace[645487068] 'agreement among raft nodes before linearized reading' (duration: 105.482367ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:54.024179Z","caller":"traceutil/trace.go:171","msg":"trace[95633821] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"117.521423ms","start":"2026-09-19T01:52:53.90661Z","end":"2026-09-19T01:52:54.024132Z","steps":["trace[95633821] 'process raft request' (duration: 65.817086ms)","trace[95633821] 'compare' (duration: 50.913141ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:52:54.024213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.490672ms","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-09-19T01:52:54.02463Z","caller":"traceutil/trace.go:171","msg":"trace[1646772715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5177; }","duration":"110.013404ms","start":"2026-09-19T01:52:53.914604Z","end":"2026-09-19T01:52:54.024618Z","steps":["trace[1646772715] 'agreement among raft nodes before linearized reading' (duration: 109.287708ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:53:24.813505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2898} {"level":"info","ts":"2026-09-19T01:53:24.914064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2898,"took":"99.217758ms","hash":38747183,"current-db-size-bytes":54214656,"current-db-size":"54 MB","current-db-size-in-use-bytes":28721152,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-19T01:53:24.91413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":38747183,"revision":2898,"compact-revision":-1} {"level":"info","ts":"2026-09-19T01:54:36.40628Z","caller":"traceutil/trace.go:171","msg":"trace[1915620179] linearizableReadLoop","detail":"{readStateIndex:6198; appliedIndex:6197; }","duration":"134.153452ms","start":"2026-09-19T01:54:36.272098Z","end":"2026-09-19T01:54:36.406252Z","steps":["trace[1915620179] 'read index received' (duration: 134.00895ms)","trace[1915620179] 'applied index is now lower than readState.Index' (duration: 143.352µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:54:36.40633Z","caller":"traceutil/trace.go:171","msg":"trace[426592014] transaction","detail":"{read_only:false; response_revision:5976; number_of_response:1; }","duration":"142.650262ms","start":"2026-09-19T01:54:36.263659Z","end":"2026-09-19T01:54:36.406309Z","steps":["trace[426592014] 'process raft request' (duration: 142.437018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:36.406733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.605022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-19T01:54:36.40681Z","caller":"traceutil/trace.go:171","msg":"trace[1267328176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5976; }","duration":"134.700564ms","start":"2026-09-19T01:54:36.272094Z","end":"2026-09-19T01:54:36.406795Z","steps":["trace[1267328176] 'agreement among raft nodes before linearized reading' (duration: 134.284145ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:10.34294Z","caller":"traceutil/trace.go:171","msg":"trace[348961320] transaction","detail":"{read_only:false; response_revision:6597; number_of_response:1; }","duration":"120.365941ms","start":"2026-09-19T01:56:10.222556Z","end":"2026-09-19T01:56:10.342922Z","steps":["trace[348961320] 'process raft request' (duration: 120.11783ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:31.248621Z","caller":"traceutil/trace.go:171","msg":"trace[1173300427] transaction","detail":"{read_only:false; response_revision:6763; number_of_response:1; }","duration":"119.370474ms","start":"2026-09-19T01:56:31.129228Z","end":"2026-09-19T01:56:31.248598Z","steps":["trace[1173300427] 'process raft request' (duration: 101.951282ms)","trace[1173300427] 'compare' (duration: 17.179181ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:58:24.82022Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5474} {"level":"info","ts":"2026-09-19T01:58:24.960299Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5474,"took":"138.481692ms","hash":223194026,"current-db-size-bytes":54214656,"current-db-size":"54 MB","current-db-size-in-use-bytes":18722816,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T01:58:24.960364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":223194026,"revision":5474,"compact-revision":2898} {"level":"info","ts":"2026-09-19T02:03:24.831564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7418} {"level":"info","ts":"2026-09-19T02:03:24.895812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7418,"took":"61.833282ms","hash":290762408,"current-db-size-bytes":54214656,"current-db-size":"54 MB","current-db-size-in-use-bytes":15224832,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-09-19T02:03:24.8959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":290762408,"revision":7418,"compact-revision":5474} {"level":"info","ts":"2026-09-19T02:04:47.908813Z","caller":"traceutil/trace.go:171","msg":"trace[1978372957] linearizableReadLoop","detail":"{readStateIndex:9704; appliedIndex:9703; }","duration":"138.932212ms","start":"2026-09-19T02:04:47.769852Z","end":"2026-09-19T02:04:47.908784Z","steps":["trace[1978372957] 'read index received' (duration: 138.624594ms)","trace[1978372957] 'applied index is now lower than readState.Index' (duration: 306.808µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:04:47.908871Z","caller":"traceutil/trace.go:171","msg":"trace[1102238335] transaction","detail":"{read_only:false; response_revision:9348; number_of_response:1; }","duration":"211.672786ms","start":"2026-09-19T02:04:47.697161Z","end":"2026-09-19T02:04:47.908833Z","steps":["trace[1102238335] 'process raft request' (duration: 211.43736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:47.90907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.189368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/keystone-api\" ","response":"range_response_count:1 size:931"} {"level":"info","ts":"2026-09-19T02:04:47.90912Z","caller":"traceutil/trace.go:171","msg":"trace[259434669] range","detail":"{range_begin:/registry/services/specs/openstack/keystone-api; range_end:; response_count:1; response_revision:9348; }","duration":"139.288701ms","start":"2026-09-19T02:04:47.769816Z","end":"2026-09-19T02:04:47.909105Z","steps":["trace[259434669] 'agreement among raft nodes before linearized reading' (duration: 139.111207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:47.910718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.286603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached\" ","response":"range_response_count:1 size:1164"} {"level":"info","ts":"2026-09-19T02:04:47.910878Z","caller":"traceutil/trace.go:171","msg":"trace[1073729253] range","detail":"{range_begin:/registry/services/specs/openstack/memcached; range_end:; response_count:1; response_revision:9348; }","duration":"125.519609ms","start":"2026-09-19T02:04:47.785333Z","end":"2026-09-19T02:04:47.910853Z","steps":["trace[1073729253] 'agreement among raft nodes before linearized reading' (duration: 123.920069ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:48.045656Z","caller":"traceutil/trace.go:171","msg":"trace[1386018893] linearizableReadLoop","detail":"{readStateIndex:9706; appliedIndex:9705; }","duration":"129.625723ms","start":"2026-09-19T02:04:47.916003Z","end":"2026-09-19T02:04:48.045629Z","steps":["trace[1386018893] 'read index received' (duration: 16.082459ms)","trace[1386018893] 'applied index is now lower than readState.Index' (duration: 113.542244ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:04:48.045713Z","caller":"traceutil/trace.go:171","msg":"trace[1251409910] transaction","detail":"{read_only:false; response_revision:9350; number_of_response:1; }","duration":"131.148853ms","start":"2026-09-19T02:04:47.914523Z","end":"2026-09-19T02:04:48.045672Z","steps":["trace[1251409910] 'process raft request' (duration: 72.722314ms)","trace[1251409910] 'compare' (duration: 58.239434ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:04:48.045962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.941772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached\" ","response":"range_response_count:1 size:1164"} {"level":"info","ts":"2026-09-19T02:04:48.046002Z","caller":"traceutil/trace.go:171","msg":"trace[1529514875] range","detail":"{range_begin:/registry/services/specs/openstack/memcached; range_end:; response_count:1; response_revision:9350; }","duration":"130.040815ms","start":"2026-09-19T02:04:47.915949Z","end":"2026-09-19T02:04:48.045989Z","steps":["trace[1529514875] 'agreement among raft nodes before linearized reading' (duration: 129.82529ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:48.046124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.547842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/keystone-api\" ","response":"range_response_count:1 size:931"} {"level":"info","ts":"2026-09-19T02:04:48.046221Z","caller":"traceutil/trace.go:171","msg":"trace[1225805539] range","detail":"{range_begin:/registry/services/specs/openstack/keystone-api; range_end:; response_count:1; response_revision:9350; }","duration":"129.727617ms","start":"2026-09-19T02:04:47.916468Z","end":"2026-09-19T02:04:48.046196Z","steps":["trace[1225805539] 'agreement among raft nodes before linearized reading' (duration: 129.45857ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:48.577243Z","caller":"traceutil/trace.go:171","msg":"trace[434646859] linearizableReadLoop","detail":"{readStateIndex:9711; appliedIndex:9710; }","duration":"110.336961ms","start":"2026-09-19T02:04:48.466884Z","end":"2026-09-19T02:04:48.577221Z","steps":["trace[434646859] 'read index received' (duration: 99.544066ms)","trace[434646859] 'applied index is now lower than readState.Index' (duration: 10.791555ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:04:48.577296Z","caller":"traceutil/trace.go:171","msg":"trace[215643106] transaction","detail":"{read_only:false; response_revision:9354; number_of_response:1; }","duration":"136.245911ms","start":"2026-09-19T02:04:48.441005Z","end":"2026-09-19T02:04:48.577251Z","steps":["trace[215643106] 'process raft request' (duration: 125.389814ms)","trace[215643106] 'compare' (duration: 10.58337ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:04:48.577779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.756738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/percona-xtradb-cluster-operator\" ","response":"range_response_count:1 size:686"} {"level":"info","ts":"2026-09-19T02:04:48.577847Z","caller":"traceutil/trace.go:171","msg":"trace[1486074023] range","detail":"{range_begin:/registry/services/endpoints/openstack/percona-xtradb-cluster-operator; range_end:; response_count:1; response_revision:9354; }","duration":"133.878601ms","start":"2026-09-19T02:04:48.443949Z","end":"2026-09-19T02:04:48.577828Z","steps":["trace[1486074023] 'agreement among raft nodes before linearized reading' (duration: 133.402198ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:49.053367Z","caller":"traceutil/trace.go:171","msg":"trace[424570097] transaction","detail":"{read_only:false; response_revision:9355; number_of_response:1; }","duration":"122.748876ms","start":"2026-09-19T02:04:48.930583Z","end":"2026-09-19T02:04:49.053332Z","steps":["trace[424570097] 'process raft request' (duration: 122.50143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:49.187702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.08489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T02:04:49.187798Z","caller":"traceutil/trace.go:171","msg":"trace[765344122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9355; }","duration":"113.221653ms","start":"2026-09-19T02:04:49.074553Z","end":"2026-09-19T02:04:49.187775Z","steps":["trace[765344122] 'range keys from in-memory index tree' (duration: 112.971707ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:50.045778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.71478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-19T02:04:50.045833Z","caller":"traceutil/trace.go:171","msg":"trace[1435141827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9358; }","duration":"132.803572ms","start":"2026-09-19T02:04:49.913016Z","end":"2026-09-19T02:04:50.04582Z","steps":["trace[1435141827] 'range keys from in-memory index tree' (duration: 132.601657ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:50.046057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.063572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-19T02:04:50.046651Z","caller":"traceutil/trace.go:171","msg":"trace[1144255259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9358; }","duration":"110.635627ms","start":"2026-09-19T02:04:49.935952Z","end":"2026-09-19T02:04:50.046587Z","steps":["trace[1144255259] 'range keys from in-memory index tree' (duration: 109.900028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:50.046085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.577982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T02:04:50.046803Z","caller":"traceutil/trace.go:171","msg":"trace[1451477253] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9358; }","duration":"116.326092ms","start":"2026-09-19T02:04:49.930455Z","end":"2026-09-19T02:04:50.046781Z","steps":["trace[1451477253] 'count revisions from in-memory index tree' (duration: 115.526461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:50.045792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.056768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T02:04:50.046987Z","caller":"traceutil/trace.go:171","msg":"trace[1482544816] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9358; }","duration":"174.196686ms","start":"2026-09-19T02:04:49.872657Z","end":"2026-09-19T02:04:50.046853Z","steps":["trace[1482544816] 'range keys from in-memory index tree' (duration: 172.979985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:50.434899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.962597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T02:04:50.4352Z","caller":"traceutil/trace.go:171","msg":"trace[1063185849] transaction","detail":"{read_only:false; response_revision:9360; number_of_response:1; }","duration":"383.228385ms","start":"2026-09-19T02:04:50.051948Z","end":"2026-09-19T02:04:50.435176Z","steps":["trace[1063185849] 'process raft request' (duration: 210.878668ms)","trace[1063185849] 'compare' (duration: 171.762923ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:04:50.435264Z","caller":"traceutil/trace.go:171","msg":"trace[616418983] linearizableReadLoop","detail":"{readStateIndex:9718; appliedIndex:9715; }","duration":"381.192723ms","start":"2026-09-19T02:04:50.054047Z","end":"2026-09-19T02:04:50.43524Z","steps":["trace[616418983] 'read index received' (duration: 8.844205ms)","trace[616418983] 'applied index is now lower than readState.Index' (duration: 372.347028ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:04:50.435426Z","caller":"traceutil/trace.go:171","msg":"trace[70170220] transaction","detail":"{read_only:false; response_revision:9361; number_of_response:1; }","duration":"381.713005ms","start":"2026-09-19T02:04:50.05363Z","end":"2026-09-19T02:04:50.435343Z","steps":["trace[70170220] 'process raft request' (duration: 381.389287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:50.435577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.517171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-19T02:04:50.435651Z","caller":"traceutil/trace.go:171","msg":"trace[1875548877] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9361; }","duration":"381.651484ms","start":"2026-09-19T02:04:50.053983Z","end":"2026-09-19T02:04:50.435635Z","steps":["trace[1875548877] 'agreement among raft nodes before linearized reading' (duration: 381.462969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:50.435695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:04:50.05397Z","time spent":"381.715386ms","remote":"127.0.0.1:58990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-19T02:04:50.43592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.343702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T02:04:50.435951Z","caller":"traceutil/trace.go:171","msg":"trace[387677919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9361; }","duration":"228.416164ms","start":"2026-09-19T02:04:50.207528Z","end":"2026-09-19T02:04:50.435944Z","steps":["trace[387677919] 'agreement among raft nodes before linearized reading' (duration: 228.333022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:50.435962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.244699ms","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":"warn","ts":"2026-09-19T02:04:50.436011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.017743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" ","response":"range_response_count:1 size:18203"} {"level":"info","ts":"2026-09-19T02:04:50.43606Z","caller":"traceutil/trace.go:171","msg":"trace[1772154856] 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:9361; }","duration":"345.389513ms","start":"2026-09-19T02:04:50.09065Z","end":"2026-09-19T02:04:50.436039Z","steps":["trace[1772154856] 'agreement among raft nodes before linearized reading' (duration: 345.232719ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:50.436076Z","caller":"traceutil/trace.go:171","msg":"trace[954729523] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-pxc; range_end:; response_count:1; response_revision:9361; }","duration":"281.124966ms","start":"2026-09-19T02:04:50.154935Z","end":"2026-09-19T02:04:50.43606Z","steps":["trace[954729523] 'agreement among raft nodes before linearized reading' (duration: 280.86647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:50.43611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:04:50.090635Z","time spent":"345.464234ms","remote":"127.0.0.1:33084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-09-19T02:04:50.436099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.298297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-19T02:04:50.435749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:04:50.053622Z","time spent":"381.944621ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T02:04:50.436164Z","caller":"traceutil/trace.go:171","msg":"trace[746746019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9361; }","duration":"350.404669ms","start":"2026-09-19T02:04:50.085741Z","end":"2026-09-19T02:04:50.436145Z","steps":["trace[746746019] 'agreement among raft nodes before linearized reading' (duration: 349.784473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:50.436234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:04:50.085721Z","time spent":"350.498791ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T02:04:50.436192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.776656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9582"} {"level":"warn","ts":"2026-09-19T02:04:50.436179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:04:50.05193Z","time spent":"383.328917ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T02:04:50.436421Z","caller":"traceutil/trace.go:171","msg":"trace[2025034723] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:9361; }","duration":"279.078564ms","start":"2026-09-19T02:04:50.157294Z","end":"2026-09-19T02:04:50.436373Z","steps":["trace[2025034723] 'agreement among raft nodes before linearized reading' (duration: 278.471989ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:52.641346Z","caller":"traceutil/trace.go:171","msg":"trace[202788769] linearizableReadLoop","detail":"{readStateIndex:9728; appliedIndex:9727; }","duration":"158.982983ms","start":"2026-09-19T02:04:52.482333Z","end":"2026-09-19T02:04:52.641316Z","steps":["trace[202788769] 'read index received' (duration: 145.838699ms)","trace[202788769] 'applied index is now lower than readState.Index' (duration: 13.143204ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:04:52.641434Z","caller":"traceutil/trace.go:171","msg":"trace[151633746] transaction","detail":"{read_only:false; response_revision:9371; number_of_response:1; }","duration":"175.881293ms","start":"2026-09-19T02:04:52.465523Z","end":"2026-09-19T02:04:52.641405Z","steps":["trace[151633746] 'process raft request' (duration: 162.702667ms)","trace[151633746] 'compare' (duration: 12.95661ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:04:52.64151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.168138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T02:04:52.641557Z","caller":"traceutil/trace.go:171","msg":"trace[954163111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9371; }","duration":"159.2664ms","start":"2026-09-19T02:04:52.482276Z","end":"2026-09-19T02:04:52.641543Z","steps":["trace[954163111] 'agreement among raft nodes before linearized reading' (duration: 159.167468ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:52.66631Z","caller":"traceutil/trace.go:171","msg":"trace[908028359] transaction","detail":"{read_only:false; response_revision:9372; number_of_response:1; }","duration":"177.389682ms","start":"2026-09-19T02:04:52.488899Z","end":"2026-09-19T02:04:52.666288Z","steps":["trace[908028359] 'process raft request' (duration: 177.276699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:53.378516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.661367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T02:04:53.378671Z","caller":"traceutil/trace.go:171","msg":"trace[1243687756] transaction","detail":"{read_only:false; response_revision:9377; number_of_response:1; }","duration":"124.458563ms","start":"2026-09-19T02:04:53.254177Z","end":"2026-09-19T02:04:53.378636Z","steps":["trace[1243687756] 'process raft request' (duration: 14.597571ms)","trace[1243687756] 'compare' (duration: 109.537474ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:04:53.468742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.055047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/\" range_end:\"/registry/pods/envoy-gateway-system0\" limit:500 ","response":"range_response_count:1 size:5487"} {"level":"info","ts":"2026-09-19T02:04:53.46883Z","caller":"traceutil/trace.go:171","msg":"trace[1703676247] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/; range_end:/registry/pods/envoy-gateway-system0; response_count:1; response_revision:9377; }","duration":"156.15708ms","start":"2026-09-19T02:04:53.312648Z","end":"2026-09-19T02:04:53.468805Z","steps":["trace[1703676247] 'agreement among raft nodes before linearized reading' (duration: 66.168163ms)","trace[1703676247] 'range keys from in-memory index tree' (duration: 89.752101ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:04:53.468866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.088572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/auth-system/keycloak-0\" ","response":"range_response_count:1 size:7961"} {"level":"info","ts":"2026-09-19T02:04:53.468928Z","caller":"traceutil/trace.go:171","msg":"trace[2113051716] range","detail":"{range_begin:/registry/pods/auth-system/keycloak-0; range_end:; response_count:1; response_revision:9377; }","duration":"144.194755ms","start":"2026-09-19T02:04:53.324715Z","end":"2026-09-19T02:04:53.46891Z","steps":["trace[2113051716] 'agreement among raft nodes before linearized reading' (duration: 143.902617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:54.36899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.345099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" limit:500 ","response":"range_response_count:6 size:61834"} {"level":"info","ts":"2026-09-19T02:04:54.369091Z","caller":"traceutil/trace.go:171","msg":"trace[1463698287] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:9380; }","duration":"122.533313ms","start":"2026-09-19T02:04:54.246535Z","end":"2026-09-19T02:04:54.369069Z","steps":["trace[1463698287] 'range keys from in-memory index tree' (duration: 122.063522ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:54.557958Z","caller":"traceutil/trace.go:171","msg":"trace[1459937105] transaction","detail":"{read_only:false; response_revision:9381; number_of_response:1; }","duration":"100.576155ms","start":"2026-09-19T02:04:54.457358Z","end":"2026-09-19T02:04:54.557934Z","steps":["trace[1459937105] 'process raft request' (duration: 100.382651ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:55.36323Z","caller":"traceutil/trace.go:171","msg":"trace[569431863] linearizableReadLoop","detail":"{readStateIndex:9745; appliedIndex:9744; }","duration":"140.731674ms","start":"2026-09-19T02:04:55.222478Z","end":"2026-09-19T02:04:55.36321Z","steps":["trace[569431863] 'read index received' (duration: 140.625812ms)","trace[569431863] 'applied index is now lower than readState.Index' (duration: 104.862µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:04:55.363396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.902768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-webhook-7d6b59cfdc-lnf2v\" ","response":"range_response_count:1 size:4875"} {"level":"info","ts":"2026-09-19T02:04:55.363373Z","caller":"traceutil/trace.go:171","msg":"trace[1861637841] transaction","detail":"{read_only:false; response_revision:9387; number_of_response:1; }","duration":"143.809862ms","start":"2026-09-19T02:04:55.219521Z","end":"2026-09-19T02:04:55.36333Z","steps":["trace[1861637841] 'process raft request' (duration: 143.568666ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:55.36343Z","caller":"traceutil/trace.go:171","msg":"trace[1208501231] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-webhook-7d6b59cfdc-lnf2v; range_end:; response_count:1; response_revision:9387; }","duration":"140.96742ms","start":"2026-09-19T02:04:55.22245Z","end":"2026-09-19T02:04:55.363417Z","steps":["trace[1208501231] 'agreement among raft nodes before linearized reading' (duration: 140.839006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:56.716337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.806412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-19T02:04:56.716355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.069524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-znnfw\" ","response":"range_response_count:1 size:7124"} {"level":"info","ts":"2026-09-19T02:04:56.716446Z","caller":"traceutil/trace.go:171","msg":"trace[1631239814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9391; }","duration":"110.938065ms","start":"2026-09-19T02:04:56.605486Z","end":"2026-09-19T02:04:56.716424Z","steps":["trace[1631239814] 'range keys from in-memory index tree' (duration: 110.755831ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:56.716453Z","caller":"traceutil/trace.go:171","msg":"trace[1682336092] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-znnfw; range_end:; response_count:1; response_revision:9391; }","duration":"125.195817ms","start":"2026-09-19T02:04:56.591242Z","end":"2026-09-19T02:04:56.716438Z","steps":["trace[1682336092] 'range keys from in-memory index tree' (duration: 124.90755ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:57.528644Z","caller":"traceutil/trace.go:171","msg":"trace[457592688] transaction","detail":"{read_only:false; response_revision:9397; number_of_response:1; }","duration":"129.502275ms","start":"2026-09-19T02:04:57.399113Z","end":"2026-09-19T02:04:57.528615Z","steps":["trace[457592688] 'process raft request' (duration: 129.267629ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:57.678822Z","caller":"traceutil/trace.go:171","msg":"trace[1423423549] transaction","detail":"{read_only:false; response_revision:9398; number_of_response:1; }","duration":"143.975732ms","start":"2026-09-19T02:04:57.534822Z","end":"2026-09-19T02:04:57.678798Z","steps":["trace[1423423549] 'process raft request' (duration: 143.786557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:58.653415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.326803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lndmj\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-09-19T02:04:58.653481Z","caller":"traceutil/trace.go:171","msg":"trace[53905481] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lndmj; range_end:; response_count:1; response_revision:9400; }","duration":"122.437585ms","start":"2026-09-19T02:04:58.531027Z","end":"2026-09-19T02:04:58.653465Z","steps":["trace[53905481] 'range keys from in-memory index tree' (duration: 121.938422ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:59.381882Z","caller":"traceutil/trace.go:171","msg":"trace[526933265] transaction","detail":"{read_only:false; response_revision:9403; number_of_response:1; }","duration":"490.42688ms","start":"2026-09-19T02:04:58.891424Z","end":"2026-09-19T02:04:59.381851Z","steps":["trace[526933265] 'process raft request' (duration: 440.848044ms)","trace[526933265] 'compare' (duration: 49.241568ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:04:59.382036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:04:58.891405Z","time spent":"490.552393ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T02:04:59.595201Z","caller":"traceutil/trace.go:171","msg":"trace[1643664761] transaction","detail":"{read_only:false; response_revision:9404; number_of_response:1; }","duration":"703.52355ms","start":"2026-09-19T02:04:58.891628Z","end":"2026-09-19T02:04:59.595151Z","steps":["trace[1643664761] 'process raft request' (duration: 645.339086ms)","trace[1643664761] 'compare' (duration: 57.900777ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:04:59.59548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:04:58.891607Z","time spent":"703.786537ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T02:04:59.595596Z","caller":"traceutil/trace.go:171","msg":"trace[1913511422] transaction","detail":"{read_only:false; response_revision:9405; number_of_response:1; }","duration":"703.925352ms","start":"2026-09-19T02:04:58.891648Z","end":"2026-09-19T02:04:59.595573Z","steps":["trace[1913511422] 'process raft request' (duration: 703.417678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:59.595705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:04:58.891633Z","time spent":"704.014623ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T02:04:59.595802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.250361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T02:04:59.59583Z","caller":"traceutil/trace.go:171","msg":"trace[158829114] transaction","detail":"{read_only:false; response_revision:9406; number_of_response:1; }","duration":"126.089646ms","start":"2026-09-19T02:04:59.469726Z","end":"2026-09-19T02:04:59.595816Z","steps":["trace[158829114] 'process raft request' (duration: 125.591012ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:04:59.595867Z","caller":"traceutil/trace.go:171","msg":"trace[204642330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9406; }","duration":"523.355253ms","start":"2026-09-19T02:04:59.072495Z","end":"2026-09-19T02:04:59.59585Z","steps":["trace[204642330] 'agreement among raft nodes before linearized reading' (duration: 523.184009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:04:59.595912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:04:59.072472Z","time spent":"523.429766ms","remote":"127.0.0.1:58808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-19T02:04:59.596084Z","caller":"traceutil/trace.go:171","msg":"trace[1061969087] linearizableReadLoop","detail":"{readStateIndex:9764; appliedIndex:9761; }","duration":"522.805169ms","start":"2026-09-19T02:04:59.07258Z","end":"2026-09-19T02:04:59.595385Z","steps":["trace[1061969087] 'read index received' (duration: 259.697352ms)","trace[1061969087] 'applied index is now lower than readState.Index' (duration: 263.106717ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:04:59.596382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.912205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lndmj\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-09-19T02:04:59.596473Z","caller":"traceutil/trace.go:171","msg":"trace[1081199849] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lndmj; range_end:; response_count:1; response_revision:9407; }","duration":"299.001577ms","start":"2026-09-19T02:04:59.297453Z","end":"2026-09-19T02:04:59.596455Z","steps":["trace[1081199849] 'agreement among raft nodes before linearized reading' (duration: 298.777222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:05:00.93321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.21161ms","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":"warn","ts":"2026-09-19T02:05:00.933282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.602461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-9cdr2\" ","response":"range_response_count:1 size:4694"} {"level":"info","ts":"2026-09-19T02:05:00.933307Z","caller":"traceutil/trace.go:171","msg":"trace[56556885] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:9412; }","duration":"177.358603ms","start":"2026-09-19T02:05:00.755929Z","end":"2026-09-19T02:05:00.933287Z","steps":["trace[56556885] 'range keys from in-memory index tree' (duration: 177.060286ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:05:00.933352Z","caller":"traceutil/trace.go:171","msg":"trace[1228650531] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-9cdr2; range_end:; response_count:1; response_revision:9412; }","duration":"153.714304ms","start":"2026-09-19T02:05:00.779619Z","end":"2026-09-19T02:05:00.933334Z","steps":["trace[1228650531] 'range keys from in-memory index tree' (duration: 153.361265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:05:01.154358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.10979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-9cdr2\" ","response":"range_response_count:1 size:4694"} {"level":"info","ts":"2026-09-19T02:05:01.154424Z","caller":"traceutil/trace.go:171","msg":"trace[1418336538] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-9cdr2; range_end:; response_count:1; response_revision:9413; }","duration":"116.219863ms","start":"2026-09-19T02:05:01.038189Z","end":"2026-09-19T02:05:01.154409Z","steps":["trace[1418336538] 'range keys from in-memory index tree' (duration: 115.958036ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:05:01.705388Z","caller":"traceutil/trace.go:171","msg":"trace[558678470] transaction","detail":"{read_only:false; response_revision:9416; number_of_response:1; }","duration":"103.481909ms","start":"2026-09-19T02:05:01.601868Z","end":"2026-09-19T02:05:01.70535Z","steps":["trace[558678470] 'process raft request' (duration: 103.287524ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:05:01.705529Z","caller":"traceutil/trace.go:171","msg":"trace[97227731] transaction","detail":"{read_only:false; response_revision:9417; number_of_response:1; }","duration":"100.934837ms","start":"2026-09-19T02:05:01.604566Z","end":"2026-09-19T02:05:01.705501Z","steps":["trace[97227731] 'process raft request' (duration: 100.68269ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:05:01.705619Z","caller":"traceutil/trace.go:171","msg":"trace[413595569] transaction","detail":"{read_only:false; response_revision:9418; number_of_response:1; }","duration":"100.079704ms","start":"2026-09-19T02:05:01.605482Z","end":"2026-09-19T02:05:01.705562Z","steps":["trace[413595569] 'process raft request' (duration: 99.801776ms)"],"step_count":1}