{"level":"warn","ts":"2026-10-09T02:08:12.765418Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T02:08:12.765592Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36: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.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36: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-10-09T02:08:12.765724Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T02:08:12.765755Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-10-09T02:08:12.765809Z","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-10-09T02:08:12.766999Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-10-09T02:08:12.767164Z","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.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36: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-10-09T02:08:12.774258Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.600561ms"} {"level":"info","ts":"2026-10-09T02:08:12.784859Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-10-09T02:08:12.785012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T02:08:12.785104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-10-09T02:08:12.785147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T02:08:12.785166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-10-09T02:08:12.785233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-10-09T02:08:12.795041Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T02:08:12.79693Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T02:08:12.798488Z","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-10-09T02:08:12.800896Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T02:08:12.803366Z","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-10-09T02:08:12.803452Z","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-10-09T02:08:12.803462Z","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-10-09T02:08:12.803509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-10-09T02:08:12.804128Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-10-09T02:08:12.803415Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T02:08:12.804959Z","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-10-09T02:08:12.805131Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T02:08:12.80516Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T02:08:12.80522Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-10-09T02:08:12.805231Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-10-09T02:08:13.787079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T02:08:13.787151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T02:08:13.787173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-10-09T02:08:13.787187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-10-09T02:08:13.787194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-10-09T02:08:13.787204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-10-09T02:08:13.78723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-10-09T02:08:13.78897Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T02:08:13.78911Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T02:08:13.789052Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:08:13.789203Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T02:08:13.789931Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T02:08:13.789982Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T02:08:13.790665Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:08:13.790927Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:08:13.790986Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:08:13.791276Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-10-09T02:08:13.793441Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T02:08:28.057527Z","caller":"traceutil/trace.go:171","msg":"trace[457944166] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"109.562638ms","start":"2026-10-09T02:08:27.947918Z","end":"2026-10-09T02:08:28.05748Z","steps":["trace[457944166] 'process raft request' (duration: 109.285893ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:08:39.284861Z","caller":"traceutil/trace.go:171","msg":"trace[1291163694] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"314.762776ms","start":"2026-10-09T02:08:38.970073Z","end":"2026-10-09T02:08:39.284836Z","steps":["trace[1291163694] 'process raft request' (duration: 314.631754ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:08:39.285342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:08:38.970052Z","time spent":"314.875347ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:09:03.219133Z","caller":"traceutil/trace.go:171","msg":"trace[1914339604] transaction","detail":"{read_only:false; number_of_response:1; response_revision:519; }","duration":"158.082662ms","start":"2026-10-09T02:09:03.061024Z","end":"2026-10-09T02:09:03.219106Z","steps":["trace[1914339604] 'process raft request' (duration: 157.901449ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.229241Z","caller":"traceutil/trace.go:171","msg":"trace[264569343] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"158.341355ms","start":"2026-10-09T02:09:03.070868Z","end":"2026-10-09T02:09:03.22921Z","steps":["trace[264569343] 'process raft request' (duration: 158.127662ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.229521Z","caller":"traceutil/trace.go:171","msg":"trace[925022687] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"142.482557ms","start":"2026-10-09T02:09:03.08703Z","end":"2026-10-09T02:09:03.229513Z","steps":["trace[925022687] 'process raft request' (duration: 142.204102ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.229503Z","caller":"traceutil/trace.go:171","msg":"trace[329947404] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"149.573038ms","start":"2026-10-09T02:09:03.079882Z","end":"2026-10-09T02:09:03.229455Z","steps":["trace[329947404] 'process raft request' (duration: 149.295794ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.232194Z","caller":"traceutil/trace.go:171","msg":"trace[397179048] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"138.955924ms","start":"2026-10-09T02:09:03.090689Z","end":"2026-10-09T02:09:03.229645Z","steps":["trace[397179048] 'process raft request' (duration: 138.868233ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.232622Z","caller":"traceutil/trace.go:171","msg":"trace[1214926643] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"145.166264ms","start":"2026-10-09T02:09:03.087441Z","end":"2026-10-09T02:09:03.232607Z","steps":["trace[1214926643] 'process raft request' (duration: 142.004329ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.232814Z","caller":"traceutil/trace.go:171","msg":"trace[450025521] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"145.372478ms","start":"2026-10-09T02:09:03.087362Z","end":"2026-10-09T02:09:03.232734Z","steps":["trace[450025521] 'process raft request' (duration: 141.970299ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.232941Z","caller":"traceutil/trace.go:171","msg":"trace[1675427010] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"145.630222ms","start":"2026-10-09T02:09:03.087299Z","end":"2026-10-09T02:09:03.232929Z","steps":["trace[1675427010] 'process raft request' (duration: 141.977829ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.233184Z","caller":"traceutil/trace.go:171","msg":"trace[179032203] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"162.258722ms","start":"2026-10-09T02:09:03.070891Z","end":"2026-10-09T02:09:03.233149Z","steps":["trace[179032203] 'process raft request' (duration: 158.237524ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.233665Z","caller":"traceutil/trace.go:171","msg":"trace[470464150] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"141.750926ms","start":"2026-10-09T02:09:03.091804Z","end":"2026-10-09T02:09:03.233555Z","steps":["trace[470464150] 'process raft request' (duration: 137.818909ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.236236Z","caller":"traceutil/trace.go:171","msg":"trace[2006586986] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"143.007144ms","start":"2026-10-09T02:09:03.093093Z","end":"2026-10-09T02:09:03.2361Z","steps":["trace[2006586986] 'process raft request' (duration: 138.979835ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.242203Z","caller":"traceutil/trace.go:171","msg":"trace[1087294322] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"134.306739ms","start":"2026-10-09T02:09:03.104283Z","end":"2026-10-09T02:09:03.238589Z","steps":["trace[1087294322] 'process raft request' (duration: 130.092817ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.248449Z","caller":"traceutil/trace.go:171","msg":"trace[395189514] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"140.68191ms","start":"2026-10-09T02:09:03.107747Z","end":"2026-10-09T02:09:03.248429Z","steps":["trace[395189514] 'process raft request' (duration: 134.461451ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:03.248541Z","caller":"traceutil/trace.go:171","msg":"trace[1660181696] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"134.100705ms","start":"2026-10-09T02:09:03.114434Z","end":"2026-10-09T02:09:03.248535Z","steps":["trace[1660181696] 'process raft request' (duration: 133.629299ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:09.465424Z","caller":"traceutil/trace.go:171","msg":"trace[1840585135] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"234.148675ms","start":"2026-10-09T02:09:09.231246Z","end":"2026-10-09T02:09:09.465395Z","steps":["trace[1840585135] 'process raft request' (duration: 233.938482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:09:09.797074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.131672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:09:09.797379Z","caller":"traceutil/trace.go:171","msg":"trace[1031748631] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"313.70515ms","start":"2026-10-09T02:09:09.483642Z","end":"2026-10-09T02:09:09.797347Z","steps":["trace[1031748631] 'process raft request' (duration: 160.761326ms)","trace[1031748631] 'compare' (duration: 151.485044ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:09:09.79756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:09:09.48362Z","time spent":"313.851942ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:09:09.809555Z","caller":"traceutil/trace.go:171","msg":"trace[545269153] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"281.553265ms","start":"2026-10-09T02:09:09.527973Z","end":"2026-10-09T02:09:09.809526Z","steps":["trace[545269153] 'process raft request' (duration: 270.60904ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:09.809541Z","caller":"traceutil/trace.go:171","msg":"trace[80835875] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"261.922858ms","start":"2026-10-09T02:09:09.547543Z","end":"2026-10-09T02:09:09.809466Z","steps":["trace[80835875] 'read index received' (duration: 97.003624ms)","trace[80835875] 'applied index is now lower than readState.Index' (duration: 164.917984ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:09:09.809748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.181462ms","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-10-09T02:09:09.809955Z","caller":"traceutil/trace.go:171","msg":"trace[619224268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"262.373014ms","start":"2026-10-09T02:09:09.547509Z","end":"2026-10-09T02:09:09.809882Z","steps":["trace[619224268] 'agreement among raft nodes before linearized reading' (duration: 262.09309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:09:09.810169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.302815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:09:09.810291Z","caller":"traceutil/trace.go:171","msg":"trace[2035323683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"224.418586ms","start":"2026-10-09T02:09:09.585819Z","end":"2026-10-09T02:09:09.810237Z","steps":["trace[2035323683] 'agreement among raft nodes before linearized reading' (duration: 224.228154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:09:10.436324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.866871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:09:10.437103Z","caller":"traceutil/trace.go:171","msg":"trace[71525947] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"620.552184ms","start":"2026-10-09T02:09:09.815877Z","end":"2026-10-09T02:09:10.436429Z","steps":["trace[71525947] 'process raft request' (duration: 498.481581ms)","trace[71525947] 'compare' (duration: 121.637467ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:09:10.437191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:09:09.815854Z","time spent":"621.297794ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:09:10.44805Z","caller":"traceutil/trace.go:171","msg":"trace[351044273] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:646; }","duration":"157.641305ms","start":"2026-10-09T02:09:10.29038Z","end":"2026-10-09T02:09:10.448021Z","steps":["trace[351044273] 'read index received' (duration: 23.994078ms)","trace[351044273] 'applied index is now lower than readState.Index' (duration: 133.646247ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:09:10.448174Z","caller":"traceutil/trace.go:171","msg":"trace[1126001631] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"558.297833ms","start":"2026-10-09T02:09:09.889861Z","end":"2026-10-09T02:09:10.448159Z","steps":["trace[1126001631] 'process raft request' (duration: 557.969148ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:09:10.448277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:09:09.889835Z","time spent":"558.365373ms","remote":"127.0.0.1:35762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T02:09:10.448434Z","caller":"traceutil/trace.go:171","msg":"trace[576354173] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"200.624901ms","start":"2026-10-09T02:09:10.247777Z","end":"2026-10-09T02:09:10.448402Z","steps":["trace[576354173] 'process raft request' (duration: 200.185445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:09:10.448428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.99179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-09T02:09:10.448553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.34881ms","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-10-09T02:09:10.448624Z","caller":"traceutil/trace.go:171","msg":"trace[938448858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"157.489191ms","start":"2026-10-09T02:09:10.291121Z","end":"2026-10-09T02:09:10.44861Z","steps":["trace[938448858] 'agreement among raft nodes before linearized reading' (duration: 157.218218ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:09:10.448541Z","caller":"traceutil/trace.go:171","msg":"trace[1635945885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"158.184463ms","start":"2026-10-09T02:09:10.290334Z","end":"2026-10-09T02:09:10.448518Z","steps":["trace[1635945885] 'agreement among raft nodes before linearized reading' (duration: 157.840798ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:09:27.011813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.338438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:09:27.012042Z","caller":"traceutil/trace.go:171","msg":"trace[179585216] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:818; }","duration":"342.016633ms","start":"2026-10-09T02:09:26.670004Z","end":"2026-10-09T02:09:27.012021Z","steps":["trace[179585216] 'read index received' (duration: 142.247789ms)","trace[179585216] 'applied index is now lower than readState.Index' (duration: 199.767474ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:09:27.012186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.179635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:09:27.01217Z","caller":"traceutil/trace.go:171","msg":"trace[1002949964] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"422.466967ms","start":"2026-10-09T02:09:26.589622Z","end":"2026-10-09T02:09:27.012089Z","steps":["trace[1002949964] 'process raft request' (duration: 222.736483ms)","trace[1002949964] 'compare' (duration: 198.659569ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:09:27.01226Z","caller":"traceutil/trace.go:171","msg":"trace[1972837621] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:792; }","duration":"342.294796ms","start":"2026-10-09T02:09:26.669952Z","end":"2026-10-09T02:09:27.012247Z","steps":["trace[1972837621] 'agreement among raft nodes before linearized reading' (duration: 342.129494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:09:27.012336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:09:26.669934Z","time spent":"342.358466ms","remote":"127.0.0.1:35612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-10-09T02:09:27.012327Z","caller":"traceutil/trace.go:171","msg":"trace[703449950] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"422.327064ms","start":"2026-10-09T02:09:26.589963Z","end":"2026-10-09T02:09:27.01229Z","steps":["trace[703449950] 'process raft request' (duration: 421.98941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:09:27.012404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:09:26.589593Z","time spent":"422.67636ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:09:27.01256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:09:26.589925Z","time spent":"422.530368ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:09:27.044744Z","caller":"traceutil/trace.go:171","msg":"trace[858176384] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"136.215625ms","start":"2026-10-09T02:09:26.9085Z","end":"2026-10-09T02:09:27.044715Z","steps":["trace[858176384] 'process raft request' (duration: 135.991452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:09:58.470684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.24357ms","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-10-09T02:09:58.470783Z","caller":"traceutil/trace.go:171","msg":"trace[16981229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1193; }","duration":"124.421271ms","start":"2026-10-09T02:09:58.34634Z","end":"2026-10-09T02:09:58.470761Z","steps":["trace[16981229] 'range keys from in-memory index tree' (duration: 124.029876ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:10:14.71618Z","caller":"traceutil/trace.go:171","msg":"trace[294351315] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"126.154796ms","start":"2026-10-09T02:10:14.589993Z","end":"2026-10-09T02:10:14.716147Z","steps":["trace[294351315] 'process raft request' (duration: 125.907053ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:10:48.115716Z","caller":"traceutil/trace.go:171","msg":"trace[1290510435] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"118.758795ms","start":"2026-10-09T02:10:47.996925Z","end":"2026-10-09T02:10:48.115684Z","steps":["trace[1290510435] 'process raft request' (duration: 100.256184ms)","trace[1290510435] 'compare' (duration: 18.158717ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:10:48.116205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.754295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b760e32f-5c8d-4ccf-9bb1-4457d6e3f66a\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-09T02:10:48.1163Z","caller":"traceutil/trace.go:171","msg":"trace[1667946151] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b760e32f-5c8d-4ccf-9bb1-4457d6e3f66a; range_end:; response_count:1; response_revision:1882; }","duration":"352.987818ms","start":"2026-10-09T02:10:47.76329Z","end":"2026-10-09T02:10:48.116277Z","steps":["trace[1667946151] 'range keys from in-memory index tree' (duration: 352.004605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:10:48.116336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:10:47.763263Z","time spent":"353.063219ms","remote":"127.0.0.1:35384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b760e32f-5c8d-4ccf-9bb1-4457d6e3f66a\" "} {"level":"info","ts":"2026-10-09T02:10:48.286407Z","caller":"traceutil/trace.go:171","msg":"trace[481754659] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1957; }","duration":"154.460939ms","start":"2026-10-09T02:10:48.131929Z","end":"2026-10-09T02:10:48.28639Z","steps":["trace[481754659] 'read index received' (duration: 121.495517ms)","trace[481754659] 'applied index is now lower than readState.Index' (duration: 32.962722ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:10:48.286595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.638602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:10:48.286601Z","caller":"traceutil/trace.go:171","msg":"trace[425620581] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"161.114359ms","start":"2026-10-09T02:10:48.125445Z","end":"2026-10-09T02:10:48.286559Z","steps":["trace[425620581] 'process raft request' (duration: 127.957894ms)","trace[425620581] 'compare' (duration: 32.64451ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:10:48.286329Z","caller":"traceutil/trace.go:171","msg":"trace[1343616923] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"133.633052ms","start":"2026-10-09T02:10:48.15266Z","end":"2026-10-09T02:10:48.286293Z","steps":["trace[1343616923] 'process raft request' (duration: 133.566891ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:10:48.286643Z","caller":"traceutil/trace.go:171","msg":"trace[508296021] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1885; }","duration":"154.720043ms","start":"2026-10-09T02:10:48.131909Z","end":"2026-10-09T02:10:48.286629Z","steps":["trace[508296021] 'agreement among raft nodes before linearized reading' (duration: 154.569261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:10:48.564655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.239276ms","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-10-09T02:10:48.564752Z","caller":"traceutil/trace.go:171","msg":"trace[453128411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1885; }","duration":"153.390128ms","start":"2026-10-09T02:10:48.411339Z","end":"2026-10-09T02:10:48.564729Z","steps":["trace[453128411] 'range keys from in-memory index tree' (duration: 153.061143ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:11:01.392018Z","caller":"traceutil/trace.go:171","msg":"trace[2051998224] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"212.909474ms","start":"2026-10-09T02:11:01.179072Z","end":"2026-10-09T02:11:01.391982Z","steps":["trace[2051998224] 'process raft request' (duration: 212.704821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:11:04.963135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.745955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:11:04.963263Z","caller":"traceutil/trace.go:171","msg":"trace[2008349168] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2021; }","duration":"318.873607ms","start":"2026-10-09T02:11:04.644332Z","end":"2026-10-09T02:11:04.963205Z","steps":["trace[2008349168] 'count revisions from in-memory index tree' (duration: 318.689054ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:11:04.96331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:11:04.644318Z","time spent":"318.979028ms","remote":"127.0.0.1:46732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-10-09T02:11:04.963196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.138954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:11:04.963397Z","caller":"traceutil/trace.go:171","msg":"trace[503357773] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2021; }","duration":"192.379167ms","start":"2026-10-09T02:11:04.770983Z","end":"2026-10-09T02:11:04.963362Z","steps":["trace[503357773] 'count revisions from in-memory index tree' (duration: 192.077042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:11:04.963666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.347313ms","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-10-09T02:11:04.963759Z","caller":"traceutil/trace.go:171","msg":"trace[859972061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2021; }","duration":"308.479265ms","start":"2026-10-09T02:11:04.655255Z","end":"2026-10-09T02:11:04.963734Z","steps":["trace[859972061] 'range keys from in-memory index tree' (duration: 308.157371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:11:04.963801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:11:04.655244Z","time spent":"308.547736ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T02:11:04.963863Z","caller":"traceutil/trace.go:171","msg":"trace[8697160] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"182.804123ms","start":"2026-10-09T02:11:04.78104Z","end":"2026-10-09T02:11:04.963844Z","steps":["trace[8697160] 'process raft request' (duration: 113.388376ms)","trace[8697160] 'compare' (duration: 68.275995ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:11:29.181476Z","caller":"traceutil/trace.go:171","msg":"trace[1790539621] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"132.694758ms","start":"2026-10-09T02:11:29.048735Z","end":"2026-10-09T02:11:29.18143Z","steps":["trace[1790539621] 'process raft request' (duration: 132.434331ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:11:29.410112Z","caller":"traceutil/trace.go:171","msg":"trace[1379807938] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"223.490139ms","start":"2026-10-09T02:11:29.186576Z","end":"2026-10-09T02:11:29.410066Z","steps":["trace[1379807938] 'process raft request' (duration: 136.883651ms)","trace[1379807938] 'compare' (duration: 86.303849ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:11:29.624659Z","caller":"traceutil/trace.go:171","msg":"trace[20549207] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"223.544851ms","start":"2026-10-09T02:11:29.401082Z","end":"2026-10-09T02:11:29.624626Z","steps":["trace[20549207] 'process raft request' (duration: 223.296633ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:11:29.624756Z","caller":"traceutil/trace.go:171","msg":"trace[1793827919] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"437.746807ms","start":"2026-10-09T02:11:29.186973Z","end":"2026-10-09T02:11:29.62472Z","steps":["trace[1793827919] 'process raft request' (duration: 432.696199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:11:29.624951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:11:29.186962Z","time spent":"437.891862ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:12:54.264428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.389493ms","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-10-09T02:12:54.264493Z","caller":"traceutil/trace.go:171","msg":"trace[404032161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2664; }","duration":"209.497696ms","start":"2026-10-09T02:12:54.054979Z","end":"2026-10-09T02:12:54.264476Z","steps":["trace[404032161] 'range keys from in-memory index tree' (duration: 209.26107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:54.264408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.635965ms","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-10-09T02:12:54.264832Z","caller":"traceutil/trace.go:171","msg":"trace[961824801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2664; }","duration":"221.112776ms","start":"2026-10-09T02:12:54.043697Z","end":"2026-10-09T02:12:54.264809Z","steps":["trace[961824801] 'range keys from in-memory index tree' (duration: 220.447952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:54.265286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.273774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:12:54.265493Z","caller":"traceutil/trace.go:171","msg":"trace[1561907582] transaction","detail":"{read_only:false; response_revision:2665; number_of_response:1; }","duration":"201.497682ms","start":"2026-10-09T02:12:54.06398Z","end":"2026-10-09T02:12:54.265477Z","steps":["trace[1561907582] 'process raft request' (duration: 39.926762ms)","trace[1561907582] 'compare' (duration: 160.949487ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:12:54.265725Z","caller":"traceutil/trace.go:171","msg":"trace[2011601008] transaction","detail":"{read_only:false; response_revision:2666; number_of_response:1; }","duration":"128.977366ms","start":"2026-10-09T02:12:54.13672Z","end":"2026-10-09T02:12:54.265698Z","steps":["trace[2011601008] 'process raft request' (duration: 128.70745ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:54.420824Z","caller":"traceutil/trace.go:171","msg":"trace[731820832] transaction","detail":"{read_only:false; response_revision:2669; number_of_response:1; }","duration":"102.33554ms","start":"2026-10-09T02:12:54.318466Z","end":"2026-10-09T02:12:54.420802Z","steps":["trace[731820832] 'process raft request' (duration: 102.195647ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:54.469369Z","caller":"traceutil/trace.go:171","msg":"trace[1523652407] linearizableReadLoop","detail":"{readStateIndex:2773; appliedIndex:2771; }","duration":"130.411867ms","start":"2026-10-09T02:12:54.33893Z","end":"2026-10-09T02:12:54.469342Z","steps":["trace[1523652407] 'read index received' (duration: 81.793006ms)","trace[1523652407] 'applied index is now lower than readState.Index' (duration: 48.617841ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:12:54.469491Z","caller":"traceutil/trace.go:171","msg":"trace[217371946] transaction","detail":"{read_only:false; response_revision:2670; number_of_response:1; }","duration":"149.98264ms","start":"2026-10-09T02:12:54.319494Z","end":"2026-10-09T02:12:54.469477Z","steps":["trace[217371946] 'process raft request' (duration: 149.703324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:54.469589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.634453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:12:54.469664Z","caller":"traceutil/trace.go:171","msg":"trace[1409181176] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2670; }","duration":"130.743825ms","start":"2026-10-09T02:12:54.3389Z","end":"2026-10-09T02:12:54.469644Z","steps":["trace[1409181176] 'agreement among raft nodes before linearized reading' (duration: 130.621512ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:54.622744Z","caller":"traceutil/trace.go:171","msg":"trace[656795596] transaction","detail":"{read_only:false; response_revision:2671; number_of_response:1; }","duration":"141.861255ms","start":"2026-10-09T02:12:54.480828Z","end":"2026-10-09T02:12:54.622689Z","steps":["trace[656795596] 'process raft request' (duration: 141.623989ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:54.740907Z","caller":"traceutil/trace.go:171","msg":"trace[2024044489] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"112.807746ms","start":"2026-10-09T02:12:54.628067Z","end":"2026-10-09T02:12:54.740875Z","steps":["trace[2024044489] 'process raft request' (duration: 99.111941ms)","trace[2024044489] 'compare' (duration: 13.534192ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:12:54.781278Z","caller":"traceutil/trace.go:171","msg":"trace[669598974] transaction","detail":"{read_only:false; response_revision:2673; number_of_response:1; }","duration":"152.124485ms","start":"2026-10-09T02:12:54.629124Z","end":"2026-10-09T02:12:54.781249Z","steps":["trace[669598974] 'process raft request' (duration: 151.9038ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:54.781653Z","caller":"traceutil/trace.go:171","msg":"trace[741033485] transaction","detail":"{read_only:false; response_revision:2674; number_of_response:1; }","duration":"151.717948ms","start":"2026-10-09T02:12:54.629923Z","end":"2026-10-09T02:12:54.781641Z","steps":["trace[741033485] 'process raft request' (duration: 151.270298ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:54.781834Z","caller":"traceutil/trace.go:171","msg":"trace[277238263] transaction","detail":"{read_only:false; response_revision:2676; number_of_response:1; }","duration":"149.787616ms","start":"2026-10-09T02:12:54.63202Z","end":"2026-10-09T02:12:54.781807Z","steps":["trace[277238263] 'process raft request' (duration: 149.623782ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:54.781923Z","caller":"traceutil/trace.go:171","msg":"trace[796446198] transaction","detail":"{read_only:false; response_revision:2675; number_of_response:1; }","duration":"151.895422ms","start":"2026-10-09T02:12:54.630014Z","end":"2026-10-09T02:12:54.781909Z","steps":["trace[796446198] 'process raft request' (duration: 151.392571ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:54.979802Z","caller":"traceutil/trace.go:171","msg":"trace[1915596254] linearizableReadLoop","detail":"{readStateIndex:2781; appliedIndex:2779; }","duration":"171.699159ms","start":"2026-10-09T02:12:54.808082Z","end":"2026-10-09T02:12:54.979781Z","steps":["trace[1915596254] 'read index received' (duration: 30.364196ms)","trace[1915596254] 'applied index is now lower than readState.Index' (duration: 141.333883ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:12:54.979944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.839221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:12:54.979986Z","caller":"traceutil/trace.go:171","msg":"trace[1370936557] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2678; }","duration":"171.937644ms","start":"2026-10-09T02:12:54.808036Z","end":"2026-10-09T02:12:54.979973Z","steps":["trace[1370936557] 'agreement among raft nodes before linearized reading' (duration: 171.831212ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:54.980107Z","caller":"traceutil/trace.go:171","msg":"trace[509316373] transaction","detail":"{read_only:false; response_revision:2678; number_of_response:1; }","duration":"187.995181ms","start":"2026-10-09T02:12:54.792079Z","end":"2026-10-09T02:12:54.980074Z","steps":["trace[509316373] 'process raft request' (duration: 135.569169ms)","trace[509316373] 'compare' (duration: 51.937622ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:12:55.043998Z","caller":"traceutil/trace.go:171","msg":"trace[139363614] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"187.381916ms","start":"2026-10-09T02:12:54.85659Z","end":"2026-10-09T02:12:55.043972Z","steps":["trace[139363614] 'process raft request' (duration: 187.178032ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:55.044419Z","caller":"traceutil/trace.go:171","msg":"trace[1860498359] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"155.134448ms","start":"2026-10-09T02:12:54.889232Z","end":"2026-10-09T02:12:55.044367Z","steps":["trace[1860498359] 'process raft request' (duration: 154.686539ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:55.792945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.956839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:12:55.793035Z","caller":"traceutil/trace.go:171","msg":"trace[1745870845] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2681; }","duration":"143.126423ms","start":"2026-10-09T02:12:55.649887Z","end":"2026-10-09T02:12:55.793013Z","steps":["trace[1745870845] 'count revisions from in-memory index tree' (duration: 142.860177ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:56.364053Z","caller":"traceutil/trace.go:171","msg":"trace[194217393] transaction","detail":"{read_only:false; response_revision:2683; number_of_response:1; }","duration":"137.38485ms","start":"2026-10-09T02:12:56.226645Z","end":"2026-10-09T02:12:56.364029Z","steps":["trace[194217393] 'process raft request' (duration: 137.236227ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:56.385827Z","caller":"traceutil/trace.go:171","msg":"trace[1796294605] transaction","detail":"{read_only:false; response_revision:2684; number_of_response:1; }","duration":"114.007438ms","start":"2026-10-09T02:12:56.271789Z","end":"2026-10-09T02:12:56.385797Z","steps":["trace[1796294605] 'process raft request' (duration: 113.880395ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:56.887232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.079567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:12:56.887324Z","caller":"traceutil/trace.go:171","msg":"trace[1630799509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2688; }","duration":"302.25521ms","start":"2026-10-09T02:12:56.585038Z","end":"2026-10-09T02:12:56.887293Z","steps":["trace[1630799509] 'range keys from in-memory index tree' (duration: 301.986135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:56.887369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:56.585016Z","time spent":"302.343653ms","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T02:12:56.887481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.76705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/keycloak\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:12:56.887528Z","caller":"traceutil/trace.go:171","msg":"trace[1416026745] range","detail":"{range_begin:/registry/secrets/auth-system/keycloak; range_end:; response_count:0; response_revision:2688; }","duration":"280.819161ms","start":"2026-10-09T02:12:56.606696Z","end":"2026-10-09T02:12:56.887516Z","steps":["trace[1416026745] 'range keys from in-memory index tree' (duration: 280.704428ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:57.068686Z","caller":"traceutil/trace.go:171","msg":"trace[1735576298] linearizableReadLoop","detail":"{readStateIndex:2792; appliedIndex:2791; }","duration":"180.132654ms","start":"2026-10-09T02:12:56.888526Z","end":"2026-10-09T02:12:57.068658Z","steps":["trace[1735576298] 'read index received' (duration: 180.021981ms)","trace[1735576298] 'applied index is now lower than readState.Index' (duration: 109.433µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:12:57.068757Z","caller":"traceutil/trace.go:171","msg":"trace[1936913539] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"225.118189ms","start":"2026-10-09T02:12:56.843617Z","end":"2026-10-09T02:12:57.068736Z","steps":["trace[1936913539] 'process raft request' (duration: 224.848614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:57.068906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.353808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:12:57.069049Z","caller":"traceutil/trace.go:171","msg":"trace[2133656334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2689; }","duration":"180.542182ms","start":"2026-10-09T02:12:56.88849Z","end":"2026-10-09T02:12:57.069032Z","steps":["trace[2133656334] 'agreement among raft nodes before linearized reading' (duration: 180.297237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:57.141063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.995988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/keycloak\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:12:57.141126Z","caller":"traceutil/trace.go:171","msg":"trace[2083205505] range","detail":"{range_begin:/registry/secrets/auth-system/keycloak; range_end:; response_count:0; response_revision:2690; }","duration":"209.098701ms","start":"2026-10-09T02:12:56.93201Z","end":"2026-10-09T02:12:57.141109Z","steps":["trace[2083205505] 'agreement among raft nodes before linearized reading' (duration: 208.986208ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:57.928559Z","caller":"traceutil/trace.go:171","msg":"trace[784196455] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"106.602753ms","start":"2026-10-09T02:12:57.821932Z","end":"2026-10-09T02:12:57.928535Z","steps":["trace[784196455] 'process raft request' (duration: 49.98364ms)","trace[784196455] 'compare' (duration: 56.525081ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:12:57.93173Z","caller":"traceutil/trace.go:171","msg":"trace[1111369846] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"109.748ms","start":"2026-10-09T02:12:57.821955Z","end":"2026-10-09T02:12:57.931703Z","steps":["trace[1111369846] 'process raft request' (duration: 109.640728ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:58.154686Z","caller":"traceutil/trace.go:171","msg":"trace[1554536302] linearizableReadLoop","detail":"{readStateIndex:2800; appliedIndex:2798; }","duration":"249.666286ms","start":"2026-10-09T02:12:57.905Z","end":"2026-10-09T02:12:58.154666Z","steps":["trace[1554536302] 'read index received' (duration: 26.663091ms)","trace[1554536302] 'applied index is now lower than readState.Index' (duration: 223.002275ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:12:58.154726Z","caller":"traceutil/trace.go:171","msg":"trace[1537095039] transaction","detail":"{read_only:false; response_revision:2696; number_of_response:1; }","duration":"281.219842ms","start":"2026-10-09T02:12:57.873487Z","end":"2026-10-09T02:12:58.154707Z","steps":["trace[1537095039] 'process raft request' (duration: 281.035038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:58.154951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.934232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-09T02:12:58.154997Z","caller":"traceutil/trace.go:171","msg":"trace[1707473708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2696; }","duration":"250.033264ms","start":"2026-10-09T02:12:57.904953Z","end":"2026-10-09T02:12:58.154986Z","steps":["trace[1707473708] 'agreement among raft nodes before linearized reading' (duration: 249.83129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:58.154972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.888395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-09T02:12:58.155058Z","caller":"traceutil/trace.go:171","msg":"trace[211516006] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2696; }","duration":"210.012527ms","start":"2026-10-09T02:12:57.945027Z","end":"2026-10-09T02:12:58.155039Z","steps":["trace[211516006] 'agreement among raft nodes before linearized reading' (duration: 209.772002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:58.68187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.439907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:12:58.681973Z","caller":"traceutil/trace.go:171","msg":"trace[1227227121] linearizableReadLoop","detail":"{readStateIndex:2801; appliedIndex:2800; }","duration":"520.890871ms","start":"2026-10-09T02:12:58.161065Z","end":"2026-10-09T02:12:58.681956Z","steps":["trace[1227227121] 'read index received' (duration: 307.283271ms)","trace[1227227121] 'applied index is now lower than readState.Index' (duration: 213.60583ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:12:58.682037Z","caller":"traceutil/trace.go:171","msg":"trace[2036886348] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"521.158087ms","start":"2026-10-09T02:12:58.160868Z","end":"2026-10-09T02:12:58.682026Z","steps":["trace[2036886348] 'process raft request' (duration: 307.466744ms)","trace[2036886348] 'compare' (duration: 213.10685ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:12:58.682095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:58.160849Z","time spent":"521.216728ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:12:58.682366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.28781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:8716"} {"level":"info","ts":"2026-10-09T02:12:58.682429Z","caller":"traceutil/trace.go:171","msg":"trace[54421675] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2697; }","duration":"521.405412ms","start":"2026-10-09T02:12:58.161008Z","end":"2026-10-09T02:12:58.682414Z","steps":["trace[54421675] 'agreement among raft nodes before linearized reading' (duration: 521.216098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:58.682705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:58.160996Z","time spent":"521.691228ms","remote":"127.0.0.1:35368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":8740,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-09T02:12:58.699793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.232649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-09T02:12:58.699829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.556114ms","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-10-09T02:12:58.699823Z","caller":"traceutil/trace.go:171","msg":"trace[813147328] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"308.260022ms","start":"2026-10-09T02:12:58.391535Z","end":"2026-10-09T02:12:58.699795Z","steps":["trace[813147328] 'process raft request' (duration: 308.094468ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:58.69988Z","caller":"traceutil/trace.go:171","msg":"trace[666558808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2699; }","duration":"283.635886ms","start":"2026-10-09T02:12:58.416228Z","end":"2026-10-09T02:12:58.699863Z","steps":["trace[666558808] 'agreement among raft nodes before linearized reading' (duration: 283.466923ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:58.699866Z","caller":"traceutil/trace.go:171","msg":"trace[1448052894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2699; }","duration":"114.357062ms","start":"2026-10-09T02:12:58.585491Z","end":"2026-10-09T02:12:58.699848Z","steps":["trace[1448052894] 'agreement among raft nodes before linearized reading' (duration: 114.219669ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:58.700091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.716441ms","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-10-09T02:12:58.700143Z","caller":"traceutil/trace.go:171","msg":"trace[971243582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2699; }","duration":"174.767802ms","start":"2026-10-09T02:12:58.525362Z","end":"2026-10-09T02:12:58.70013Z","steps":["trace[971243582] 'agreement among raft nodes before linearized reading' (duration: 174.640629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:58.700093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:58.391507Z","time spent":"308.378494ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:12:58.700267Z","caller":"traceutil/trace.go:171","msg":"trace[296345226] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"535.141665ms","start":"2026-10-09T02:12:58.165096Z","end":"2026-10-09T02:12:58.700238Z","steps":["trace[296345226] 'process raft request' (duration: 534.41969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:58.700397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:58.165081Z","time spent":"535.249808ms","remote":"127.0.0.1:35524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1140,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T02:12:58.700531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.648741ms","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-10-09T02:12:58.700611Z","caller":"traceutil/trace.go:171","msg":"trace[820543188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2699; }","duration":"205.742422ms","start":"2026-10-09T02:12:58.494849Z","end":"2026-10-09T02:12:58.700592Z","steps":["trace[820543188] 'agreement among raft nodes before linearized reading' (duration: 205.489917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:58.70059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.591215ms","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-10-09T02:12:58.700735Z","caller":"traceutil/trace.go:171","msg":"trace[1885715373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2699; }","duration":"231.729777ms","start":"2026-10-09T02:12:58.468952Z","end":"2026-10-09T02:12:58.700682Z","steps":["trace[1885715373] 'agreement among raft nodes before linearized reading' (duration: 231.372159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:58.700968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.197466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:12:58.701015Z","caller":"traceutil/trace.go:171","msg":"trace[1168185341] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2699; }","duration":"224.957812ms","start":"2026-10-09T02:12:58.476043Z","end":"2026-10-09T02:12:58.701001Z","steps":["trace[1168185341] 'agreement among raft nodes before linearized reading' (duration: 224.074734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.349043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:58.947574Z","time spent":"401.46093ms","remote":"127.0.0.1:37736","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-09T02:12:59.349678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.655003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:12:59.349963Z","caller":"traceutil/trace.go:171","msg":"trace[442563246] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"643.257982ms","start":"2026-10-09T02:12:58.706683Z","end":"2026-10-09T02:12:59.349941Z","steps":["trace[442563246] 'process raft request' (duration: 146.258723ms)","trace[442563246] 'compare' (duration: 496.373537ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:12:59.350018Z","caller":"traceutil/trace.go:171","msg":"trace[1217862739] linearizableReadLoop","detail":"{readStateIndex:2808; appliedIndex:2803; }","duration":"571.351386ms","start":"2026-10-09T02:12:58.778648Z","end":"2026-10-09T02:12:59.349999Z","steps":["trace[1217862739] 'read index received' (duration: 74.295396ms)","trace[1217862739] 'applied index is now lower than readState.Index' (duration: 497.05476ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:12:59.350045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:58.706656Z","time spent":"643.352884ms","remote":"127.0.0.1:35572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1066,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T02:12:59.350106Z","caller":"traceutil/trace.go:171","msg":"trace[1036466228] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"641.560014ms","start":"2026-10-09T02:12:58.708532Z","end":"2026-10-09T02:12:59.350092Z","steps":["trace[1036466228] 'process raft request' (duration: 641.415381ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:59.350105Z","caller":"traceutil/trace.go:171","msg":"trace[1596875065] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"641.991384ms","start":"2026-10-09T02:12:58.708086Z","end":"2026-10-09T02:12:59.350077Z","steps":["trace[1596875065] 'process raft request' (duration: 641.80881ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:59.350178Z","caller":"traceutil/trace.go:171","msg":"trace[936306403] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"643.062948ms","start":"2026-10-09T02:12:58.707043Z","end":"2026-10-09T02:12:59.350106Z","steps":["trace[936306403] 'process raft request' (duration: 642.72294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.350228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:58.708506Z","time spent":"641.638107ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:12:59.350024Z","caller":"traceutil/trace.go:171","msg":"trace[1803250167] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"642.938415ms","start":"2026-10-09T02:12:58.707067Z","end":"2026-10-09T02:12:59.350005Z","steps":["trace[1803250167] 'process raft request' (duration: 642.780771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.350337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:58.707025Z","time spent":"643.218261ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:12:59.350363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:58.70705Z","time spent":"643.280743ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:12:59.350376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:58.708064Z","time spent":"642.153008ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:12:59.350435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.724475ms","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-10-09T02:12:59.350651Z","caller":"traceutil/trace.go:171","msg":"trace[1525720427] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2704; }","duration":"572.003741ms","start":"2026-10-09T02:12:58.778633Z","end":"2026-10-09T02:12:59.350637Z","steps":["trace[1525720427] 'agreement among raft nodes before linearized reading' (duration: 571.49721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.350772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:58.77856Z","time spent":"572.173113ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-09T02:12:59.365877Z","caller":"traceutil/trace.go:171","msg":"trace[1180238129] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"219.958223ms","start":"2026-10-09T02:12:59.145895Z","end":"2026-10-09T02:12:59.365853Z","steps":["trace[1180238129] 'process raft request' (duration: 219.878711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.366241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.727608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:12:59.36624Z","caller":"traceutil/trace.go:171","msg":"trace[1019885289] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"292.885435ms","start":"2026-10-09T02:12:59.073326Z","end":"2026-10-09T02:12:59.366211Z","steps":["trace[1019885289] 'process raft request' (duration: 292.331613ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:59.366312Z","caller":"traceutil/trace.go:171","msg":"trace[1659804729] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2706; }","duration":"396.843841ms","start":"2026-10-09T02:12:58.969451Z","end":"2026-10-09T02:12:59.366294Z","steps":["trace[1659804729] 'agreement among raft nodes before linearized reading' (duration: 396.651277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.366554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:58.969435Z","time spent":"397.095815ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":31,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-10-09T02:12:59.656755Z","caller":"traceutil/trace.go:171","msg":"trace[1148162509] linearizableReadLoop","detail":"{readStateIndex:2813; appliedIndex:2810; }","duration":"290.660407ms","start":"2026-10-09T02:12:59.366074Z","end":"2026-10-09T02:12:59.656735Z","steps":["trace[1148162509] 'read index received' (duration: 290.486553ms)","trace[1148162509] 'applied index is now lower than readState.Index' (duration: 172.774µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:12:59.65685Z","caller":"traceutil/trace.go:171","msg":"trace[395707016] transaction","detail":"{read_only:false; response_revision:2708; number_of_response:1; }","duration":"300.109459ms","start":"2026-10-09T02:12:59.356712Z","end":"2026-10-09T02:12:59.656822Z","steps":["trace[395707016] 'process raft request' (duration: 299.898824ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:59.656977Z","caller":"traceutil/trace.go:171","msg":"trace[83546953] transaction","detail":"{read_only:false; response_revision:2709; number_of_response:1; }","duration":"299.117627ms","start":"2026-10-09T02:12:59.357848Z","end":"2026-10-09T02:12:59.656965Z","steps":["trace[83546953] 'process raft request' (duration: 298.836921ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:59.657012Z","caller":"traceutil/trace.go:171","msg":"trace[581182843] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"300.856405ms","start":"2026-10-09T02:12:59.356146Z","end":"2026-10-09T02:12:59.657003Z","steps":["trace[581182843] 'process raft request' (duration: 300.334034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.657058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:59.356091Z","time spent":"300.943378ms","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":890,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T02:12:59.656974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:59.356695Z","time spent":"300.215052ms","remote":"127.0.0.1:35254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T02:12:59.65723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.835797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:12:59.657298Z","caller":"traceutil/trace.go:171","msg":"trace[1195914191] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2709; }","duration":"301.961709ms","start":"2026-10-09T02:12:59.355318Z","end":"2026-10-09T02:12:59.657279Z","steps":["trace[1195914191] 'agreement among raft nodes before linearized reading' (duration: 301.794205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.657342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:59.355293Z","time spent":"302.041061ms","remote":"127.0.0.1:35396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-09T02:12:59.716139Z","caller":"traceutil/trace.go:171","msg":"trace[1915366999] transaction","detail":"{read_only:false; response_revision:2710; number_of_response:1; }","duration":"119.507174ms","start":"2026-10-09T02:12:59.596609Z","end":"2026-10-09T02:12:59.716117Z","steps":["trace[1915366999] 'process raft request' (duration: 119.389692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.716343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.995968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:12:59.716408Z","caller":"traceutil/trace.go:171","msg":"trace[1425393478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2710; }","duration":"130.11689ms","start":"2026-10-09T02:12:59.586277Z","end":"2026-10-09T02:12:59.716394Z","steps":["trace[1425393478] 'agreement among raft nodes before linearized reading' (duration: 129.977837ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:59.962763Z","caller":"traceutil/trace.go:171","msg":"trace[2024027988] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"301.193533ms","start":"2026-10-09T02:12:59.661548Z","end":"2026-10-09T02:12:59.962742Z","steps":["trace[2024027988] 'process raft request' (duration: 301.142352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.962883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:59.661536Z","time spent":"301.288644ms","remote":"127.0.0.1:35284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":948,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T02:12:59.962949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.313186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-09T02:12:59.962952Z","caller":"traceutil/trace.go:171","msg":"trace[1527611044] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"301.707223ms","start":"2026-10-09T02:12:59.661214Z","end":"2026-10-09T02:12:59.962921Z","steps":["trace[1527611044] 'process raft request' (duration: 301.357466ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:59.962761Z","caller":"traceutil/trace.go:171","msg":"trace[380761591] linearizableReadLoop","detail":"{readStateIndex:2816; appliedIndex:2814; }","duration":"246.498148ms","start":"2026-10-09T02:12:59.716226Z","end":"2026-10-09T02:12:59.962724Z","steps":["trace[380761591] 'read index received' (duration: 246.304203ms)","trace[380761591] 'applied index is now lower than readState.Index' (duration: 193.065µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:12:59.963064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.301196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak.18dcbaa7442e8e1f\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-10-09T02:12:59.963028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.430606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:12:59.963087Z","caller":"traceutil/trace.go:171","msg":"trace[213527074] range","detail":"{range_begin:/registry/events/auth-system/keycloak.18dcbaa7442e8e1f; range_end:; response_count:1; response_revision:2712; }","duration":"302.344846ms","start":"2026-10-09T02:12:59.660735Z","end":"2026-10-09T02:12:59.96308Z","steps":["trace[213527074] 'agreement among raft nodes before linearized reading' (duration: 302.266625ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.963093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:59.661201Z","time spent":"301.819625ms","remote":"127.0.0.1:35284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":888,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T02:12:59.963115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:59.660728Z","time spent":"302.380048ms","remote":"127.0.0.1:35254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":692,"request content":"key:\"/registry/events/auth-system/keycloak.18dcbaa7442e8e1f\" "} {"level":"info","ts":"2026-10-09T02:12:59.963119Z","caller":"traceutil/trace.go:171","msg":"trace[1692523470] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2712; }","duration":"285.56535ms","start":"2026-10-09T02:12:59.677531Z","end":"2026-10-09T02:12:59.963096Z","steps":["trace[1692523470] 'agreement among raft nodes before linearized reading' (duration: 285.378626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.963145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.908666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:12:59.963204Z","caller":"traceutil/trace.go:171","msg":"trace[432342352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2712; }","duration":"245.998057ms","start":"2026-10-09T02:12:59.717195Z","end":"2026-10-09T02:12:59.963193Z","steps":["trace[432342352] 'agreement among raft nodes before linearized reading' (duration: 245.919575ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:12:59.962985Z","caller":"traceutil/trace.go:171","msg":"trace[1483858784] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2712; }","duration":"302.387628ms","start":"2026-10-09T02:12:59.660588Z","end":"2026-10-09T02:12:59.962976Z","steps":["trace[1483858784] 'agreement among raft nodes before linearized reading' (duration: 302.214984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:12:59.963305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:12:59.660575Z","time spent":"302.715185ms","remote":"127.0.0.1:35384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-09T02:13:00.20645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.894057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:00.206547Z","caller":"traceutil/trace.go:171","msg":"trace[122680736] linearizableReadLoop","detail":"{readStateIndex:2818; appliedIndex:2816; }","duration":"230.26664ms","start":"2026-10-09T02:12:59.976265Z","end":"2026-10-09T02:13:00.206532Z","steps":["trace[122680736] 'read index received' (duration: 12.190549ms)","trace[122680736] 'applied index is now lower than readState.Index' (duration: 218.072401ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:00.206648Z","caller":"traceutil/trace.go:171","msg":"trace[518287775] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"237.978375ms","start":"2026-10-09T02:12:59.968637Z","end":"2026-10-09T02:13:00.206616Z","steps":["trace[518287775] 'process raft request' (duration: 74.842192ms)","trace[518287775] 'compare' (duration: 162.760626ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:00.206783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.515665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-10-09T02:13:00.206814Z","caller":"traceutil/trace.go:171","msg":"trace[1645573098] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2714; }","duration":"230.575476ms","start":"2026-10-09T02:12:59.976227Z","end":"2026-10-09T02:13:00.206803Z","steps":["trace[1645573098] 'agreement among raft nodes before linearized reading' (duration: 230.386822ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:00.20693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.02222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:00.207013Z","caller":"traceutil/trace.go:171","msg":"trace[2129822095] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2714; }","duration":"219.241235ms","start":"2026-10-09T02:12:59.987755Z","end":"2026-10-09T02:13:00.206996Z","steps":["trace[2129822095] 'agreement among raft nodes before linearized reading' (duration: 219.01173ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:00.345303Z","caller":"traceutil/trace.go:171","msg":"trace[1037859593] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"133.862602ms","start":"2026-10-09T02:13:00.211415Z","end":"2026-10-09T02:13:00.345278Z","steps":["trace[1037859593] 'process raft request' (duration: 133.693298ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:00.712592Z","caller":"traceutil/trace.go:171","msg":"trace[1411738340] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"499.016544ms","start":"2026-10-09T02:13:00.213552Z","end":"2026-10-09T02:13:00.712568Z","steps":["trace[1411738340] 'process raft request' (duration: 432.085763ms)","trace[1411738340] 'compare' (duration: 66.810458ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:00.712727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:00.213524Z","time spent":"499.131846ms","remote":"127.0.0.1:35238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120811,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:01.168537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.852488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:01.168853Z","caller":"traceutil/trace.go:171","msg":"trace[711835354] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"819.581068ms","start":"2026-10-09T02:13:00.349243Z","end":"2026-10-09T02:13:01.168824Z","steps":["trace[711835354] 'process raft request' (duration: 508.360502ms)","trace[711835354] 'compare' (duration: 310.691495ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:01.168956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:00.349219Z","time spent":"819.6939ms","remote":"127.0.0.1:35508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T02:13:01.169254Z","caller":"traceutil/trace.go:171","msg":"trace[542948292] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"819.731541ms","start":"2026-10-09T02:13:00.349495Z","end":"2026-10-09T02:13:01.169227Z","steps":["trace[542948292] 'process raft request' (duration: 819.144788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.169401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:00.349485Z","time spent":"819.845854ms","remote":"127.0.0.1:35360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":934,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T02:13:01.169854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.459934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-09T02:13:01.169915Z","caller":"traceutil/trace.go:171","msg":"trace[664786195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2718; }","duration":"484.550706ms","start":"2026-10-09T02:13:00.68535Z","end":"2026-10-09T02:13:01.1699Z","steps":["trace[664786195] 'agreement among raft nodes before linearized reading' (duration: 484.118286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.169965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:00.685334Z","time spent":"484.617337ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T02:13:01.169945Z","caller":"traceutil/trace.go:171","msg":"trace[1504640989] linearizableReadLoop","detail":"{readStateIndex:2822; appliedIndex:2820; }","duration":"483.578525ms","start":"2026-10-09T02:13:00.685378Z","end":"2026-10-09T02:13:01.168956Z","steps":["trace[1504640989] 'read index received' (duration: 172.207096ms)","trace[1504640989] 'applied index is now lower than readState.Index' (duration: 311.370319ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:01.175441Z","caller":"traceutil/trace.go:171","msg":"trace[106385038] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"470.726622ms","start":"2026-10-09T02:13:00.704697Z","end":"2026-10-09T02:13:01.175424Z","steps":["trace[106385038] 'process raft request' (duration: 470.488677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.175635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.20895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:120825"} {"level":"info","ts":"2026-10-09T02:13:01.175693Z","caller":"traceutil/trace.go:171","msg":"trace[1244326773] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2720; }","duration":"461.297291ms","start":"2026-10-09T02:13:00.714381Z","end":"2026-10-09T02:13:01.175679Z","steps":["trace[1244326773] 'agreement among raft nodes before linearized reading' (duration: 461.089917ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:01.175555Z","caller":"traceutil/trace.go:171","msg":"trace[236105924] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"461.454275ms","start":"2026-10-09T02:13:00.714084Z","end":"2026-10-09T02:13:01.175538Z","steps":["trace[236105924] 'process raft request' (duration: 461.19742ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.17574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:00.714372Z","time spent":"461.357252ms","remote":"127.0.0.1:35238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":120849,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-10-09T02:13:01.175863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:00.714057Z","time spent":"461.70963ms","remote":"127.0.0.1:35396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1302,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T02:13:01.1759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:00.704669Z","time spent":"470.965647ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:01.747321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.187487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:01.747571Z","caller":"traceutil/trace.go:171","msg":"trace[835633553] transaction","detail":"{read_only:false; response_revision:2721; number_of_response:1; }","duration":"572.770853ms","start":"2026-10-09T02:13:01.17476Z","end":"2026-10-09T02:13:01.747531Z","steps":["trace[835633553] 'process raft request' (duration: 124.208358ms)","trace[835633553] 'compare' (duration: 446.540842ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:01.747883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.174734Z","time spent":"573.024279ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:01.751129Z","caller":"traceutil/trace.go:171","msg":"trace[1876319754] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"570.586688ms","start":"2026-10-09T02:13:01.18052Z","end":"2026-10-09T02:13:01.751107Z","steps":["trace[1876319754] 'process raft request' (duration: 569.880813ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:01.751459Z","caller":"traceutil/trace.go:171","msg":"trace[1780855403] linearizableReadLoop","detail":"{readStateIndex:2828; appliedIndex:2824; }","duration":"567.220617ms","start":"2026-10-09T02:13:01.184212Z","end":"2026-10-09T02:13:01.751432Z","steps":["trace[1780855403] 'read index received' (duration: 114.847701ms)","trace[1780855403] 'applied index is now lower than readState.Index' (duration: 452.369056ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:01.751848Z","caller":"traceutil/trace.go:171","msg":"trace[644204470] transaction","detail":"{read_only:false; response_revision:2723; number_of_response:1; }","duration":"571.163011ms","start":"2026-10-09T02:13:01.180668Z","end":"2026-10-09T02:13:01.751831Z","steps":["trace[644204470] 'process raft request' (duration: 570.016637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.752047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.18064Z","time spent":"571.248992ms","remote":"127.0.0.1:35360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":842,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T02:13:01.752183Z","caller":"traceutil/trace.go:171","msg":"trace[329965900] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"380.725839ms","start":"2026-10-09T02:13:01.371406Z","end":"2026-10-09T02:13:01.752132Z","steps":["trace[329965900] 'process raft request' (duration: 379.376911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.75233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.371391Z","time spent":"380.862242ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:01.752811Z","caller":"traceutil/trace.go:171","msg":"trace[831508308] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"571.908386ms","start":"2026-10-09T02:13:01.180885Z","end":"2026-10-09T02:13:01.752794Z","steps":["trace[831508308] 'process raft request' (duration: 569.845063ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:01.752849Z","caller":"traceutil/trace.go:171","msg":"trace[1514712323] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"380.351941ms","start":"2026-10-09T02:13:01.372484Z","end":"2026-10-09T02:13:01.752836Z","steps":["trace[1514712323] 'process raft request' (duration: 378.343089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.752888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.180865Z","time spent":"571.993598ms","remote":"127.0.0.1:35508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1296,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T02:13:01.752908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.37247Z","time spent":"380.404982ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:01.753066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.844741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:13:01.753115Z","caller":"traceutil/trace.go:171","msg":"trace[1908888085] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2727; }","duration":"568.941133ms","start":"2026-10-09T02:13:01.184155Z","end":"2026-10-09T02:13:01.753096Z","steps":["trace[1908888085] 'agreement among raft nodes before linearized reading' (duration: 568.863331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.755471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.180479Z","time spent":"570.767582ms","remote":"127.0.0.1:35238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120811,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:01.753147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.184143Z","time spent":"568.995425ms","remote":"127.0.0.1:41288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"info","ts":"2026-10-09T02:13:01.891885Z","caller":"traceutil/trace.go:171","msg":"trace[1049949402] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"172.47324ms","start":"2026-10-09T02:13:01.719378Z","end":"2026-10-09T02:13:01.891851Z","steps":["trace[1049949402] 'process raft request' (duration: 129.861449ms)","trace[1049949402] 'compare' (duration: 42.019089ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:01.892618Z","caller":"traceutil/trace.go:171","msg":"trace[1893010667] linearizableReadLoop","detail":"{readStateIndex:2832; appliedIndex:2831; }","duration":"141.094625ms","start":"2026-10-09T02:13:01.751504Z","end":"2026-10-09T02:13:01.892599Z","steps":["trace[1893010667] 'read index received' (duration: 97.740848ms)","trace[1893010667] 'applied index is now lower than readState.Index' (duration: 43.351697ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:01.892825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.005616ms","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-10-09T02:13:01.892868Z","caller":"traceutil/trace.go:171","msg":"trace[1214564678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2728; }","duration":"537.111479ms","start":"2026-10-09T02:13:01.355745Z","end":"2026-10-09T02:13:01.892856Z","steps":["trace[1214564678] 'agreement among raft nodes before linearized reading' (duration: 536.918725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.892898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.355725Z","time spent":"537.164341ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-09T02:13:01.893091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.347709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:01.893128Z","caller":"traceutil/trace.go:171","msg":"trace[1260818812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2728; }","duration":"309.407508ms","start":"2026-10-09T02:13:01.583711Z","end":"2026-10-09T02:13:01.893118Z","steps":["trace[1260818812] 'agreement among raft nodes before linearized reading' (duration: 309.349897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.893156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.5837Z","time spent":"309.450299ms","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T02:13:01.893374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.394874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:13:01.893416Z","caller":"traceutil/trace.go:171","msg":"trace[915724060] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2728; }","duration":"425.458976ms","start":"2026-10-09T02:13:01.467943Z","end":"2026-10-09T02:13:01.893402Z","steps":["trace[915724060] 'agreement among raft nodes before linearized reading' (duration: 425.383394ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.893443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.467932Z","time spent":"425.501746ms","remote":"127.0.0.1:41306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-10-09T02:13:01.893668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.478336ms","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-10-09T02:13:01.893709Z","caller":"traceutil/trace.go:171","msg":"trace[829685805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2728; }","duration":"536.582349ms","start":"2026-10-09T02:13:01.357113Z","end":"2026-10-09T02:13:01.893696Z","steps":["trace[829685805] 'agreement among raft nodes before linearized reading' (duration: 536.466166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.893733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.357094Z","time spent":"536.63291ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T02:13:01.894467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.187872ms","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-10-09T02:13:01.894517Z","caller":"traceutil/trace.go:171","msg":"trace[2032219473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2728; }","duration":"538.255352ms","start":"2026-10-09T02:13:01.356248Z","end":"2026-10-09T02:13:01.894503Z","steps":["trace[2032219473] 'agreement among raft nodes before linearized reading' (duration: 538.11416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.894544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.356239Z","time spent":"538.298253ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T02:13:01.894675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.833435ms","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-10-09T02:13:01.894706Z","caller":"traceutil/trace.go:171","msg":"trace[1015605640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2728; }","duration":"538.881167ms","start":"2026-10-09T02:13:01.355816Z","end":"2026-10-09T02:13:01.894697Z","steps":["trace[1015605640] 'agreement among raft nodes before linearized reading' (duration: 538.802215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:01.89473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.355808Z","time spent":"538.915177ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-09T02:13:01.953328Z","caller":"traceutil/trace.go:171","msg":"trace[887617656] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"195.395686ms","start":"2026-10-09T02:13:01.757917Z","end":"2026-10-09T02:13:01.953313Z","steps":["trace[887617656] 'process raft request' (duration: 195.308445ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:02.10352Z","caller":"traceutil/trace.go:171","msg":"trace[1799102646] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"202.514822ms","start":"2026-10-09T02:13:01.900969Z","end":"2026-10-09T02:13:02.103484Z","steps":["trace[1799102646] 'process raft request' (duration: 160.557796ms)","trace[1799102646] 'compare' (duration: 41.584298ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:02.103784Z","caller":"traceutil/trace.go:171","msg":"trace[580247628] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"200.405217ms","start":"2026-10-09T02:13:01.903367Z","end":"2026-10-09T02:13:02.103772Z","steps":["trace[580247628] 'process raft request' (duration: 200.226093ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:02.103788Z","caller":"traceutil/trace.go:171","msg":"trace[327388308] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"202.673355ms","start":"2026-10-09T02:13:01.901061Z","end":"2026-10-09T02:13:02.103735Z","steps":["trace[327388308] 'process raft request' (duration: 202.146934ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:02.103651Z","caller":"traceutil/trace.go:171","msg":"trace[575732928] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"202.312607ms","start":"2026-10-09T02:13:01.901321Z","end":"2026-10-09T02:13:02.103634Z","steps":["trace[575732928] 'process raft request' (duration: 202.225465ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:02.280093Z","caller":"traceutil/trace.go:171","msg":"trace[521988838] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"322.235235ms","start":"2026-10-09T02:13:01.957815Z","end":"2026-10-09T02:13:02.28005Z","steps":["trace[521988838] 'process raft request' (duration: 276.801845ms)","trace[521988838] 'compare' (duration: 45.197275ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:02.2801Z","caller":"traceutil/trace.go:171","msg":"trace[1538590647] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"322.00303ms","start":"2026-10-09T02:13:01.958064Z","end":"2026-10-09T02:13:02.280067Z","steps":["trace[1538590647] 'process raft request' (duration: 321.898718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:02.280263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.957802Z","time spent":"322.389788ms","remote":"127.0.0.1:35360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":857,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T02:13:02.280093Z","caller":"traceutil/trace.go:171","msg":"trace[1276286159] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"319.022086ms","start":"2026-10-09T02:13:01.961044Z","end":"2026-10-09T02:13:02.280066Z","steps":["trace[1276286159] 'process raft request' (duration: 318.969875ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:02.280434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.958056Z","time spent":"322.256925ms","remote":"127.0.0.1:35508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1335,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T02:13:02.280517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:01.961033Z","time spent":"319.387165ms","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7158,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T02:13:02.526963Z","caller":"traceutil/trace.go:171","msg":"trace[2018117070] linearizableReadLoop","detail":"{readStateIndex:2841; appliedIndex:2840; }","duration":"234.335607ms","start":"2026-10-09T02:13:02.292563Z","end":"2026-10-09T02:13:02.526899Z","steps":["trace[2018117070] 'read index received' (duration: 224.058739ms)","trace[2018117070] 'applied index is now lower than readState.Index' (duration: 10.223937ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:02.527013Z","caller":"traceutil/trace.go:171","msg":"trace[289784492] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"238.240659ms","start":"2026-10-09T02:13:02.28874Z","end":"2026-10-09T02:13:02.526981Z","steps":["trace[289784492] 'process raft request' (duration: 227.865189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:02.52705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.462509ms","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-10-09T02:13:02.527149Z","caller":"traceutil/trace.go:171","msg":"trace[1256439046] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2737; }","duration":"234.613213ms","start":"2026-10-09T02:13:02.29251Z","end":"2026-10-09T02:13:02.527123Z","steps":["trace[1256439046] 'agreement among raft nodes before linearized reading' (duration: 234.341627ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:02.736428Z","caller":"traceutil/trace.go:171","msg":"trace[132459867] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"195.035488ms","start":"2026-10-09T02:13:02.541361Z","end":"2026-10-09T02:13:02.736396Z","steps":["trace[132459867] 'process raft request' (duration: 105.693912ms)","trace[132459867] 'compare' (duration: 89.167642ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:02.736545Z","caller":"traceutil/trace.go:171","msg":"trace[751278226] linearizableReadLoop","detail":"{readStateIndex:2842; appliedIndex:2841; }","duration":"179.10156ms","start":"2026-10-09T02:13:02.557396Z","end":"2026-10-09T02:13:02.736498Z","steps":["trace[751278226] 'read index received' (duration: 89.592041ms)","trace[751278226] 'applied index is now lower than readState.Index' (duration: 89.502499ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:03.010584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.199367ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T02:13:03.011012Z","caller":"traceutil/trace.go:171","msg":"trace[233065597] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"266.037085ms","start":"2026-10-09T02:13:02.744954Z","end":"2026-10-09T02:13:03.010991Z","steps":["trace[233065597] 'process raft request' (duration: 265.965883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:03.011006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.580663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7173"} {"level":"info","ts":"2026-10-09T02:13:03.011072Z","caller":"traceutil/trace.go:171","msg":"trace[1112956201] transaction","detail":"{read_only:false; response_revision:2740; number_of_response:1; }","duration":"269.181991ms","start":"2026-10-09T02:13:02.741851Z","end":"2026-10-09T02:13:03.011033Z","steps":["trace[1112956201] 'process raft request' (duration: 268.965366ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:03.011103Z","caller":"traceutil/trace.go:171","msg":"trace[1177814730] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2738; }","duration":"453.711776ms","start":"2026-10-09T02:13:02.55736Z","end":"2026-10-09T02:13:03.011072Z","steps":["trace[1177814730] 'agreement among raft nodes before linearized reading' (duration: 179.302404ms)","trace[1177814730] 'range keys from in-memory index tree' (duration: 274.010203ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:03.011204Z","caller":"traceutil/trace.go:171","msg":"trace[900050110] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"268.632529ms","start":"2026-10-09T02:13:02.74241Z","end":"2026-10-09T02:13:03.011042Z","steps":["trace[900050110] 'process raft request' (duration: 268.466906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:03.01129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:02.557343Z","time spent":"453.93147ms","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":7197,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"info","ts":"2026-10-09T02:13:03.01136Z","caller":"traceutil/trace.go:171","msg":"trace[2010991416] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"269.503019ms","start":"2026-10-09T02:13:02.741841Z","end":"2026-10-09T02:13:03.011344Z","steps":["trace[2010991416] 'process raft request' (duration: 268.863055ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:03.011451Z","caller":"traceutil/trace.go:171","msg":"trace[1982158374] linearizableReadLoop","detail":"{readStateIndex:2843; appliedIndex:2842; }","duration":"274.828191ms","start":"2026-10-09T02:13:02.73661Z","end":"2026-10-09T02:13:03.011438Z","steps":["trace[1982158374] 'read index received' (duration: 67.442µs)","trace[1982158374] 'applied index is now lower than readState.Index' (duration: 274.758939ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:03.01164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.392158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:03.012688Z","caller":"traceutil/trace.go:171","msg":"trace[390763557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2742; }","duration":"427.509813ms","start":"2026-10-09T02:13:02.585158Z","end":"2026-10-09T02:13:03.012668Z","steps":["trace[390763557] 'agreement among raft nodes before linearized reading' (duration: 426.405119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:03.012773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:02.585139Z","time spent":"427.600264ms","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T02:13:03.333749Z","caller":"traceutil/trace.go:171","msg":"trace[724400899] linearizableReadLoop","detail":"{readStateIndex:2848; appliedIndex:2847; }","duration":"307.41017ms","start":"2026-10-09T02:13:03.026306Z","end":"2026-10-09T02:13:03.333716Z","steps":["trace[724400899] 'read index received' (duration: 274.837715ms)","trace[724400899] 'applied index is now lower than readState.Index' (duration: 32.570295ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:03.334065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.726196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/auth-system/keycloak-0\" ","response":"range_response_count:1 size:6937"} {"level":"info","ts":"2026-10-09T02:13:03.334109Z","caller":"traceutil/trace.go:171","msg":"trace[231664710] range","detail":"{range_begin:/registry/pods/auth-system/keycloak-0; range_end:; response_count:1; response_revision:2743; }","duration":"307.842649ms","start":"2026-10-09T02:13:03.026252Z","end":"2026-10-09T02:13:03.334094Z","steps":["trace[231664710] 'agreement among raft nodes before linearized reading' (duration: 307.608854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:03.334144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:03.026231Z","time spent":"307.904851ms","remote":"127.0.0.1:35384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":6961,"request content":"key:\"/registry/pods/auth-system/keycloak-0\" "} {"level":"info","ts":"2026-10-09T02:13:03.334122Z","caller":"traceutil/trace.go:171","msg":"trace[1163705854] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"316.531002ms","start":"2026-10-09T02:13:03.017212Z","end":"2026-10-09T02:13:03.333743Z","steps":["trace[1163705854] 'process raft request' (duration: 283.785813ms)","trace[1163705854] 'compare' (duration: 32.400533ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:03.334516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:03.017188Z","time spent":"317.147266ms","remote":"127.0.0.1:35254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":664,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T02:13:03.69637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.270258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-10-09T02:13:03.696977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.576449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/auth-system/keycloak\" ","response":"range_response_count:1 size:905"} {"level":"info","ts":"2026-10-09T02:13:03.697058Z","caller":"traceutil/trace.go:171","msg":"trace[538864885] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"351.779554ms","start":"2026-10-09T02:13:03.344928Z","end":"2026-10-09T02:13:03.696708Z","steps":["trace[538864885] 'process raft request' (duration: 351.650782ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:03.697107Z","caller":"traceutil/trace.go:171","msg":"trace[1100872913] range","detail":"{range_begin:/registry/serviceaccounts/auth-system/keycloak; range_end:; response_count:1; response_revision:2746; }","duration":"475.713292ms","start":"2026-10-09T02:13:03.22133Z","end":"2026-10-09T02:13:03.697043Z","steps":["trace[1100872913] 'agreement among raft nodes before linearized reading' (duration: 475.410576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:03.697137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:03.344912Z","time spent":"352.187604ms","remote":"127.0.0.1:35384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7825,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:03.697178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:03.221317Z","time spent":"475.830255ms","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":929,"request content":"key:\"/registry/serviceaccounts/auth-system/keycloak\" "} {"level":"info","ts":"2026-10-09T02:13:03.697659Z","caller":"traceutil/trace.go:171","msg":"trace[1048863422] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"516.091882ms","start":"2026-10-09T02:13:03.180659Z","end":"2026-10-09T02:13:03.696751Z","steps":["trace[1048863422] 'process raft request' (duration: 515.835687ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:03.697853Z","caller":"traceutil/trace.go:171","msg":"trace[465744901] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"661.005492ms","start":"2026-10-09T02:13:03.035752Z","end":"2026-10-09T02:13:03.696758Z","steps":["trace[465744901] 'process raft request' (duration: 477.238874ms)","trace[465744901] 'compare' (duration: 183.113254ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:03.697274Z","caller":"traceutil/trace.go:171","msg":"trace[1948179774] linearizableReadLoop","detail":"{readStateIndex:2850; appliedIndex:2848; }","duration":"362.908499ms","start":"2026-10-09T02:13:03.333825Z","end":"2026-10-09T02:13:03.696733Z","steps":["trace[1948179774] 'read index received' (duration: 179.180871ms)","trace[1948179774] 'applied index is now lower than readState.Index' (duration: 183.722618ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:03.697952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:03.035731Z","time spent":"662.159777ms","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7447,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:03.698149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.15709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:03.698212Z","caller":"traceutil/trace.go:171","msg":"trace[2021583758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2746; }","duration":"112.292593ms","start":"2026-10-09T02:13:03.585906Z","end":"2026-10-09T02:13:03.698199Z","steps":["trace[2021583758] 'agreement among raft nodes before linearized reading' (duration: 112.166731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:03.698323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:03.180641Z","time spent":"517.057093ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:04.242453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.615253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:04.242659Z","caller":"traceutil/trace.go:171","msg":"trace[1577026082] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"484.491994ms","start":"2026-10-09T02:13:03.758141Z","end":"2026-10-09T02:13:04.242633Z","steps":["trace[1577026082] 'process raft request' (duration: 145.547284ms)","trace[1577026082] 'compare' (duration: 338.393028ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:04.242752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:03.758124Z","time spent":"484.583496ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:04.243188Z","caller":"traceutil/trace.go:171","msg":"trace[1488774550] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"371.948126ms","start":"2026-10-09T02:13:03.871203Z","end":"2026-10-09T02:13:04.243151Z","steps":["trace[1488774550] 'process raft request' (duration: 371.819713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:04.24354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:03.871147Z","time spent":"372.337143ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:04.243188Z","caller":"traceutil/trace.go:171","msg":"trace[1977787745] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"482.785938ms","start":"2026-10-09T02:13:03.760348Z","end":"2026-10-09T02:13:04.243134Z","steps":["trace[1977787745] 'process raft request' (duration: 482.214755ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:04.243359Z","caller":"traceutil/trace.go:171","msg":"trace[676606501] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"345.685021ms","start":"2026-10-09T02:13:03.897658Z","end":"2026-10-09T02:13:04.243343Z","steps":["trace[676606501] 'process raft request' (duration: 345.435077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:04.244502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:03.760336Z","time spent":"484.049094ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:04.244534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:03.897641Z","time spent":"346.840466ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:04.26257Z","caller":"traceutil/trace.go:171","msg":"trace[1822503357] linearizableReadLoop","detail":"{readStateIndex:2857; appliedIndex:2856; }","duration":"155.056765ms","start":"2026-10-09T02:13:04.107487Z","end":"2026-10-09T02:13:04.262544Z","steps":["trace[1822503357] 'read index received' (duration: 154.883451ms)","trace[1822503357] 'applied index is now lower than readState.Index' (duration: 171.904µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:04.262613Z","caller":"traceutil/trace.go:171","msg":"trace[903329430] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"264.753838ms","start":"2026-10-09T02:13:03.997825Z","end":"2026-10-09T02:13:04.262579Z","steps":["trace[903329430] 'process raft request' (duration: 264.575284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:04.262837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.31855ms","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-10-09T02:13:04.262877Z","caller":"traceutil/trace.go:171","msg":"trace[893765671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2752; }","duration":"155.388092ms","start":"2026-10-09T02:13:04.107478Z","end":"2026-10-09T02:13:04.262866Z","steps":["trace[893765671] 'agreement among raft nodes before linearized reading' (duration: 155.204428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:04.262873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.28543ms","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-10-09T02:13:04.262955Z","caller":"traceutil/trace.go:171","msg":"trace[903508047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2752; }","duration":"155.3613ms","start":"2026-10-09T02:13:04.107552Z","end":"2026-10-09T02:13:04.262914Z","steps":["trace[903508047] 'agreement among raft nodes before linearized reading' (duration: 155.186787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:04.262971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.596364ms","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-10-09T02:13:04.263029Z","caller":"traceutil/trace.go:171","msg":"trace[2126928719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2752; }","duration":"154.668865ms","start":"2026-10-09T02:13:04.108347Z","end":"2026-10-09T02:13:04.263016Z","steps":["trace[2126928719] 'agreement among raft nodes before linearized reading' (duration: 154.522153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:04.262966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.636784ms","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-10-09T02:13:04.263112Z","caller":"traceutil/trace.go:171","msg":"trace[1129168647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2752; }","duration":"154.820349ms","start":"2026-10-09T02:13:04.108279Z","end":"2026-10-09T02:13:04.263099Z","steps":["trace[1129168647] 'agreement among raft nodes before linearized reading' (duration: 154.586285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:04.701295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.264512ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:04.701441Z","caller":"traceutil/trace.go:171","msg":"trace[177794900] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2756; }","duration":"280.441656ms","start":"2026-10-09T02:13:04.420981Z","end":"2026-10-09T02:13:04.701423Z","steps":["trace[177794900] 'range keys from in-memory index tree' (duration: 280.243832ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:04.701693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.185249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:04.701784Z","caller":"traceutil/trace.go:171","msg":"trace[907345907] linearizableReadLoop","detail":"{readStateIndex:2862; appliedIndex:2861; }","duration":"116.825972ms","start":"2026-10-09T02:13:04.584942Z","end":"2026-10-09T02:13:04.701768Z","steps":["trace[907345907] 'read index received' (duration: 33.52µs)","trace[907345907] 'applied index is now lower than readState.Index' (duration: 116.791502ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:04.701792Z","caller":"traceutil/trace.go:171","msg":"trace[314241102] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"177.962475ms","start":"2026-10-09T02:13:04.523812Z","end":"2026-10-09T02:13:04.701774Z","steps":["trace[314241102] 'process raft request' (duration: 10.600073ms)","trace[314241102] 'compare' (duration: 167.072406ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:04.701842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.900785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:04.701866Z","caller":"traceutil/trace.go:171","msg":"trace[1619553239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2757; }","duration":"116.958715ms","start":"2026-10-09T02:13:04.584898Z","end":"2026-10-09T02:13:04.701857Z","steps":["trace[1619553239] 'agreement among raft nodes before linearized reading' (duration: 116.910354ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:04.939347Z","caller":"traceutil/trace.go:171","msg":"trace[554880074] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"191.97917ms","start":"2026-10-09T02:13:04.74732Z","end":"2026-10-09T02:13:04.9393Z","steps":["trace[554880074] 'process raft request' (duration: 163.754397ms)","trace[554880074] 'compare' (duration: 27.901116ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:05.091147Z","caller":"traceutil/trace.go:171","msg":"trace[2053787924] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"139.498004ms","start":"2026-10-09T02:13:04.951613Z","end":"2026-10-09T02:13:05.091111Z","steps":["trace[2053787924] 'process raft request' (duration: 115.967731ms)","trace[2053787924] 'compare' (duration: 22.968991ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:05.321404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.802346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-09T02:13:05.321525Z","caller":"traceutil/trace.go:171","msg":"trace[986947433] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2759; }","duration":"107.957739ms","start":"2026-10-09T02:13:05.213543Z","end":"2026-10-09T02:13:05.321501Z","steps":["trace[986947433] 'range keys from in-memory index tree' (duration: 107.609182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:05.954451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.269436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:13:05.95459Z","caller":"traceutil/trace.go:171","msg":"trace[1285971716] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2760; }","duration":"216.46245ms","start":"2026-10-09T02:13:05.738061Z","end":"2026-10-09T02:13:05.954523Z","steps":["trace[1285971716] 'count revisions from in-memory index tree' (duration: 216.122163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:05.95493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.870922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-09T02:13:05.95529Z","caller":"traceutil/trace.go:171","msg":"trace[935369987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2760; }","duration":"156.073736ms","start":"2026-10-09T02:13:05.798974Z","end":"2026-10-09T02:13:05.955048Z","steps":["trace[935369987] 'range keys from in-memory index tree' (duration: 155.370781ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:06.129138Z","caller":"traceutil/trace.go:171","msg":"trace[512224522] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"169.636434ms","start":"2026-10-09T02:13:05.959478Z","end":"2026-10-09T02:13:06.129115Z","steps":["trace[512224522] 'process raft request' (duration: 169.465131ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:06.360986Z","caller":"traceutil/trace.go:171","msg":"trace[794046656] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"113.105341ms","start":"2026-10-09T02:13:06.247856Z","end":"2026-10-09T02:13:06.360961Z","steps":["trace[794046656] 'process raft request' (duration: 112.757193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:06.712996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.672519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:06.713188Z","caller":"traceutil/trace.go:171","msg":"trace[1275526851] linearizableReadLoop","detail":"{readStateIndex:2869; appliedIndex:2867; }","duration":"436.655354ms","start":"2026-10-09T02:13:06.276494Z","end":"2026-10-09T02:13:06.713149Z","steps":["trace[1275526851] 'read index received' (duration: 84.706308ms)","trace[1275526851] 'applied index is now lower than readState.Index' (duration: 351.947986ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:06.713294Z","caller":"traceutil/trace.go:171","msg":"trace[14096123] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"462.897601ms","start":"2026-10-09T02:13:06.250381Z","end":"2026-10-09T02:13:06.713279Z","steps":["trace[14096123] 'process raft request' (duration: 462.707777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:06.713388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:06.250345Z","time spent":"462.990003ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:06.713411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.845219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-09T02:13:06.713442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.151988ms","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-10-09T02:13:06.713483Z","caller":"traceutil/trace.go:171","msg":"trace[181835350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2764; }","duration":"384.217589ms","start":"2026-10-09T02:13:06.329253Z","end":"2026-10-09T02:13:06.713471Z","steps":["trace[181835350] 'agreement among raft nodes before linearized reading' (duration: 384.066036ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:06.713487Z","caller":"traceutil/trace.go:171","msg":"trace[908730360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2764; }","duration":"127.980921ms","start":"2026-10-09T02:13:06.585488Z","end":"2026-10-09T02:13:06.713469Z","steps":["trace[908730360] 'agreement among raft nodes before linearized reading' (duration: 127.851749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:06.713485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.735489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-10-09T02:13:06.713515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:06.329243Z","time spent":"384.26531ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-09T02:13:06.713529Z","caller":"traceutil/trace.go:171","msg":"trace[518841614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2764; }","duration":"383.79852ms","start":"2026-10-09T02:13:06.329718Z","end":"2026-10-09T02:13:06.713517Z","steps":["trace[518841614] 'agreement among raft nodes before linearized reading' (duration: 383.659637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:06.713561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:06.329707Z","time spent":"383.843961ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T02:13:06.713638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.25697ms","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-10-09T02:13:06.713665Z","caller":"traceutil/trace.go:171","msg":"trace[437922245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2764; }","duration":"384.27863ms","start":"2026-10-09T02:13:06.329375Z","end":"2026-10-09T02:13:06.713654Z","steps":["trace[437922245] 'agreement among raft nodes before linearized reading' (duration: 384.208999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:06.713686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:06.329319Z","time spent":"384.362053ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T02:13:06.713861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.349588ms","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-10-09T02:13:06.714438Z","caller":"traceutil/trace.go:171","msg":"trace[1550260293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2764; }","duration":"437.962712ms","start":"2026-10-09T02:13:06.27646Z","end":"2026-10-09T02:13:06.714423Z","steps":["trace[1550260293] 'agreement among raft nodes before linearized reading' (duration: 437.262757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:06.714548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:06.276446Z","time spent":"438.079044ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-09T02:13:06.714036Z","caller":"traceutil/trace.go:171","msg":"trace[334248295] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"464.094356ms","start":"2026-10-09T02:13:06.249783Z","end":"2026-10-09T02:13:06.713877Z","steps":["trace[334248295] 'process raft request' (duration: 318.457896ms)","trace[334248295] 'compare' (duration: 144.419084ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:06.714665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:06.249726Z","time spent":"464.856493ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:07.041912Z","caller":"traceutil/trace.go:171","msg":"trace[968253145] transaction","detail":"{read_only:false; response_revision:2767; number_of_response:1; }","duration":"322.346165ms","start":"2026-10-09T02:13:06.719539Z","end":"2026-10-09T02:13:07.041885Z","steps":["trace[968253145] 'process raft request' (duration: 322.237843ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:07.041915Z","caller":"traceutil/trace.go:171","msg":"trace[1230420892] transaction","detail":"{read_only:false; response_revision:2766; number_of_response:1; }","duration":"322.808535ms","start":"2026-10-09T02:13:06.719078Z","end":"2026-10-09T02:13:07.041886Z","steps":["trace[1230420892] 'process raft request' (duration: 322.52925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:07.04208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:06.719528Z","time spent":"322.455728ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:07.042204Z","caller":"traceutil/trace.go:171","msg":"trace[1729542396] transaction","detail":"{read_only:false; response_revision:2768; number_of_response:1; }","duration":"322.421517ms","start":"2026-10-09T02:13:06.719711Z","end":"2026-10-09T02:13:07.042133Z","steps":["trace[1729542396] 'process raft request' (duration: 322.12382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:07.042295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:06.719056Z","time spent":"322.941358ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:07.042384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:06.719702Z","time spent":"322.552119ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:07.044185Z","caller":"traceutil/trace.go:171","msg":"trace[349987897] linearizableReadLoop","detail":"{readStateIndex:2874; appliedIndex:2870; }","duration":"185.771234ms","start":"2026-10-09T02:13:06.85838Z","end":"2026-10-09T02:13:07.044151Z","steps":["trace[349987897] 'read index received' (duration: 183.225422ms)","trace[349987897] 'applied index is now lower than readState.Index' (duration: 2.544622ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:07.044401Z","caller":"traceutil/trace.go:171","msg":"trace[946754339] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"189.86682ms","start":"2026-10-09T02:13:06.854523Z","end":"2026-10-09T02:13:07.04439Z","steps":["trace[946754339] 'process raft request' (duration: 189.554074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:07.044668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.270906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:13:07.045074Z","caller":"traceutil/trace.go:171","msg":"trace[273794639] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2769; }","duration":"186.457459ms","start":"2026-10-09T02:13:06.85831Z","end":"2026-10-09T02:13:07.044767Z","steps":["trace[273794639] 'agreement among raft nodes before linearized reading' (duration: 186.250655ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:08.572924Z","caller":"traceutil/trace.go:171","msg":"trace[1948411434] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"203.561214ms","start":"2026-10-09T02:13:08.369326Z","end":"2026-10-09T02:13:08.572887Z","steps":["trace[1948411434] 'process raft request' (duration: 203.3636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:09.026519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.866321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:09.026889Z","caller":"traceutil/trace.go:171","msg":"trace[1082260212] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"307.831058ms","start":"2026-10-09T02:13:08.719042Z","end":"2026-10-09T02:13:09.026873Z","steps":["trace[1082260212] 'process raft request' (duration: 63.516178ms)","trace[1082260212] 'compare' (duration: 243.675777ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:09.026933Z","caller":"traceutil/trace.go:171","msg":"trace[1915065546] linearizableReadLoop","detail":"{readStateIndex:2882; appliedIndex:2880; }","duration":"215.794597ms","start":"2026-10-09T02:13:08.811127Z","end":"2026-10-09T02:13:09.026922Z","steps":["trace[1915065546] 'read index received' (duration: 46.648077ms)","trace[1915065546] 'applied index is now lower than readState.Index' (duration: 169.14568ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:09.026948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:08.719018Z","time spent":"307.900679ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:09.027063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.936721ms","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-10-09T02:13:09.027081Z","caller":"traceutil/trace.go:171","msg":"trace[767667292] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2776; }","duration":"215.982031ms","start":"2026-10-09T02:13:08.811093Z","end":"2026-10-09T02:13:09.027075Z","steps":["trace[767667292] 'agreement among raft nodes before linearized reading' (duration: 215.872969ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:09.026957Z","caller":"traceutil/trace.go:171","msg":"trace[918212610] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"306.200854ms","start":"2026-10-09T02:13:08.720733Z","end":"2026-10-09T02:13:09.026934Z","steps":["trace[918212610] 'process raft request' (duration: 305.870077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:09.027252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:08.72072Z","time spent":"306.426949ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:09.272691Z","caller":"traceutil/trace.go:171","msg":"trace[196507013] linearizableReadLoop","detail":"{readStateIndex:2886; appliedIndex:2883; }","duration":"197.132771ms","start":"2026-10-09T02:13:09.07551Z","end":"2026-10-09T02:13:09.272643Z","steps":["trace[196507013] 'read index received' (duration: 16.948281ms)","trace[196507013] 'applied index is now lower than readState.Index' (duration: 180.18298ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:09.272755Z","caller":"traceutil/trace.go:171","msg":"trace[1763466072] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"199.164893ms","start":"2026-10-09T02:13:09.073516Z","end":"2026-10-09T02:13:09.272681Z","steps":["trace[1763466072] 'process raft request' (duration: 164.041146ms)","trace[1763466072] 'compare' (duration: 34.877242ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:09.272854Z","caller":"traceutil/trace.go:171","msg":"trace[2074030254] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"197.353806ms","start":"2026-10-09T02:13:09.075471Z","end":"2026-10-09T02:13:09.272825Z","steps":["trace[2074030254] 'process raft request' (duration: 197.097611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:09.272963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.421657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-10-09T02:13:09.273022Z","caller":"traceutil/trace.go:171","msg":"trace[138659918] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2780; }","duration":"197.52258ms","start":"2026-10-09T02:13:09.075483Z","end":"2026-10-09T02:13:09.273006Z","steps":["trace[138659918] 'agreement among raft nodes before linearized reading' (duration: 197.294295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:09.798369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.774404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:09.798482Z","caller":"traceutil/trace.go:171","msg":"trace[1743020173] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2780; }","duration":"226.910307ms","start":"2026-10-09T02:13:09.571539Z","end":"2026-10-09T02:13:09.798449Z","steps":["trace[1743020173] 'count revisions from in-memory index tree' (duration: 226.655261ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:11.438089Z","caller":"traceutil/trace.go:171","msg":"trace[773148556] linearizableReadLoop","detail":"{readStateIndex:2891; appliedIndex:2889; }","duration":"403.482987ms","start":"2026-10-09T02:13:11.034583Z","end":"2026-10-09T02:13:11.438066Z","steps":["trace[773148556] 'read index received' (duration: 73.145506ms)","trace[773148556] 'applied index is now lower than readState.Index' (duration: 330.336461ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:11.438321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.713579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-09T02:13:11.438321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.727631ms","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-10-09T02:13:11.438353Z","caller":"traceutil/trace.go:171","msg":"trace[1779221210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2785; }","duration":"365.79444ms","start":"2026-10-09T02:13:11.072548Z","end":"2026-10-09T02:13:11.438342Z","steps":["trace[1779221210] 'agreement among raft nodes before linearized reading' (duration: 365.667037ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:11.438352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.507345ms","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-10-09T02:13:11.438379Z","caller":"traceutil/trace.go:171","msg":"trace[939084680] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2785; }","duration":"403.831704ms","start":"2026-10-09T02:13:11.034536Z","end":"2026-10-09T02:13:11.438368Z","steps":["trace[939084680] 'agreement among raft nodes before linearized reading' (duration: 403.6538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:11.438386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:11.072534Z","time spent":"365.844362ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-09T02:13:11.438398Z","caller":"traceutil/trace.go:171","msg":"trace[78066277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2785; }","duration":"161.591747ms","start":"2026-10-09T02:13:11.276793Z","end":"2026-10-09T02:13:11.438385Z","steps":["trace[78066277] 'agreement among raft nodes before linearized reading' (duration: 161.456674ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:11.438408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:11.034524Z","time spent":"403.876384ms","remote":"127.0.0.1:35396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-10-09T02:13:11.438457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.595107ms","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-10-09T02:13:11.438566Z","caller":"traceutil/trace.go:171","msg":"trace[532212289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2785; }","duration":"161.717589ms","start":"2026-10-09T02:13:11.276792Z","end":"2026-10-09T02:13:11.43851Z","steps":["trace[532212289] 'agreement among raft nodes before linearized reading' (duration: 161.503605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:11.43864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.888768ms","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-10-09T02:13:11.438138Z","caller":"traceutil/trace.go:171","msg":"trace[118625586] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"405.880136ms","start":"2026-10-09T02:13:11.032238Z","end":"2026-10-09T02:13:11.438118Z","steps":["trace[118625586] 'process raft request' (duration: 405.672773ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:11.438756Z","caller":"traceutil/trace.go:171","msg":"trace[1141184759] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2785; }","duration":"343.051302ms","start":"2026-10-09T02:13:11.095679Z","end":"2026-10-09T02:13:11.438731Z","steps":["trace[1141184759] 'agreement among raft nodes before linearized reading' (duration: 342.678804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:11.438818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:11.095669Z","time spent":"343.139584ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T02:13:11.438894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:11.032206Z","time spent":"406.58702ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:11.908958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.460801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:11.909218Z","caller":"traceutil/trace.go:171","msg":"trace[788501101] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"465.32754ms","start":"2026-10-09T02:13:11.443857Z","end":"2026-10-09T02:13:11.909184Z","steps":["trace[788501101] 'process raft request' (duration: 153.521192ms)","trace[788501101] 'compare' (duration: 311.338538ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:11.909364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:11.443805Z","time spent":"465.474333ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:11.909372Z","caller":"traceutil/trace.go:171","msg":"trace[678619356] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"159.386442ms","start":"2026-10-09T02:13:11.749959Z","end":"2026-10-09T02:13:11.909345Z","steps":["trace[678619356] 'process raft request' (duration: 159.336301ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:11.909616Z","caller":"traceutil/trace.go:171","msg":"trace[1715532893] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"465.087765ms","start":"2026-10-09T02:13:11.444508Z","end":"2026-10-09T02:13:11.909596Z","steps":["trace[1715532893] 'process raft request' (duration: 464.646206ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:11.909676Z","caller":"traceutil/trace.go:171","msg":"trace[1121492513] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"463.773317ms","start":"2026-10-09T02:13:11.445883Z","end":"2026-10-09T02:13:11.909657Z","steps":["trace[1121492513] 'process raft request' (duration: 463.359158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:11.909699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:11.444502Z","time spent":"465.152406ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:11.909721Z","caller":"traceutil/trace.go:171","msg":"trace[244809538] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"465.262648ms","start":"2026-10-09T02:13:11.444447Z","end":"2026-10-09T02:13:11.909709Z","steps":["trace[244809538] 'process raft request' (duration: 464.628515ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:11.909735Z","caller":"traceutil/trace.go:171","msg":"trace[785940163] linearizableReadLoop","detail":"{readStateIndex:2894; appliedIndex:2891; }","duration":"464.124474ms","start":"2026-10-09T02:13:11.445592Z","end":"2026-10-09T02:13:11.909716Z","steps":["trace[785940163] 'read index received' (duration: 151.790715ms)","trace[785940163] 'applied index is now lower than readState.Index' (duration: 312.327639ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:11.909778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:11.444433Z","time spent":"465.31268ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:11.9098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:11.44587Z","time spent":"463.842779ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:11.90998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.374351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-10-09T02:13:11.910063Z","caller":"traceutil/trace.go:171","msg":"trace[918372002] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2790; }","duration":"464.484022ms","start":"2026-10-09T02:13:11.445559Z","end":"2026-10-09T02:13:11.910043Z","steps":["trace[918372002] 'agreement among raft nodes before linearized reading' (duration: 464.231537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:11.910114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:11.445545Z","time spent":"464.555413ms","remote":"127.0.0.1:35396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1341,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-10-09T02:13:11.973426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.991357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:11.973483Z","caller":"traceutil/trace.go:171","msg":"trace[1397504471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2791; }","duration":"388.10479ms","start":"2026-10-09T02:13:11.585361Z","end":"2026-10-09T02:13:11.973466Z","steps":["trace[1397504471] 'agreement among raft nodes before linearized reading' (duration: 388.011658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:11.973515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:11.585345Z","time spent":"388.16386ms","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T02:13:11.973466Z","caller":"traceutil/trace.go:171","msg":"trace[344975833] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"143.593366ms","start":"2026-10-09T02:13:11.829833Z","end":"2026-10-09T02:13:11.973427Z","steps":["trace[344975833] 'process raft request' (duration: 143.368681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:11.973528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.109893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:11.973586Z","caller":"traceutil/trace.go:171","msg":"trace[207616] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2791; }","duration":"343.212146ms","start":"2026-10-09T02:13:11.630356Z","end":"2026-10-09T02:13:11.973569Z","steps":["trace[207616] 'agreement among raft nodes before linearized reading' (duration: 343.115304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:11.973628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:11.630332Z","time spent":"343.285807ms","remote":"127.0.0.1:46858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-09T02:13:12.415443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.900705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:12.415548Z","caller":"traceutil/trace.go:171","msg":"trace[1086233414] linearizableReadLoop","detail":"{readStateIndex:2898; appliedIndex:2897; }","duration":"170.958841ms","start":"2026-10-09T02:13:12.244571Z","end":"2026-10-09T02:13:12.415529Z","steps":["trace[1086233414] 'read index received' (duration: 59.262µs)","trace[1086233414] 'applied index is now lower than readState.Index' (duration: 170.897909ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:12.415637Z","caller":"traceutil/trace.go:171","msg":"trace[1418602549] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"365.981664ms","start":"2026-10-09T02:13:12.049642Z","end":"2026-10-09T02:13:12.415624Z","steps":["trace[1418602549] 'process raft request' (duration: 163.797553ms)","trace[1418602549] 'compare' (duration: 201.503067ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:12.415715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:12.049619Z","time spent":"366.049705ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:12.416057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.471471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:12.416128Z","caller":"traceutil/trace.go:171","msg":"trace[27324948] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2792; }","duration":"171.598144ms","start":"2026-10-09T02:13:12.244512Z","end":"2026-10-09T02:13:12.416111Z","steps":["trace[27324948] 'agreement among raft nodes before linearized reading' (duration: 171.476321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:12.416289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.229688ms","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-10-09T02:13:12.416392Z","caller":"traceutil/trace.go:171","msg":"trace[1903454025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2792; }","duration":"139.385672ms","start":"2026-10-09T02:13:12.276968Z","end":"2026-10-09T02:13:12.416353Z","steps":["trace[1903454025] 'agreement among raft nodes before linearized reading' (duration: 139.025994ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:13.701153Z","caller":"traceutil/trace.go:171","msg":"trace[385857997] linearizableReadLoop","detail":"{readStateIndex:2903; appliedIndex:2902; }","duration":"115.092376ms","start":"2026-10-09T02:13:13.586006Z","end":"2026-10-09T02:13:13.701099Z","steps":["trace[385857997] 'read index received' (duration: 114.854071ms)","trace[385857997] 'applied index is now lower than readState.Index' (duration: 237.215µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:13.701232Z","caller":"traceutil/trace.go:171","msg":"trace[2121830533] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"256.392509ms","start":"2026-10-09T02:13:13.444809Z","end":"2026-10-09T02:13:13.701201Z","steps":["trace[2121830533] 'process raft request' (duration: 256.143044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:13.702047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.005346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:13.702218Z","caller":"traceutil/trace.go:171","msg":"trace[329224067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2796; }","duration":"116.20707ms","start":"2026-10-09T02:13:13.585951Z","end":"2026-10-09T02:13:13.702158Z","steps":["trace[329224067] 'agreement among raft nodes before linearized reading' (duration: 115.312792ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:14.351436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.397537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:14.351703Z","caller":"traceutil/trace.go:171","msg":"trace[1062289373] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"372.09185ms","start":"2026-10-09T02:13:13.979585Z","end":"2026-10-09T02:13:14.351677Z","steps":["trace[1062289373] 'process raft request' (duration: 371.979738ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:14.351763Z","caller":"traceutil/trace.go:171","msg":"trace[1942056019] linearizableReadLoop","detail":"{readStateIndex:2904; appliedIndex:2903; }","duration":"437.454979ms","start":"2026-10-09T02:13:13.914287Z","end":"2026-10-09T02:13:14.351742Z","steps":["trace[1942056019] 'read index received' (duration: 272.661373ms)","trace[1942056019] 'applied index is now lower than readState.Index' (duration: 164.790806ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:14.351811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:13.979552Z","time spent":"372.202803ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:14.351991Z","caller":"traceutil/trace.go:171","msg":"trace[1769275643] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"543.173603ms","start":"2026-10-09T02:13:13.808799Z","end":"2026-10-09T02:13:14.351972Z","steps":["trace[1769275643] 'process raft request' (duration: 378.133653ms)","trace[1769275643] 'compare' (duration: 164.090001ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:14.35207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:13.808776Z","time spent":"543.252785ms","remote":"127.0.0.1:38016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:14.352469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.169892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-09T02:13:14.352564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.934799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:13:14.352632Z","caller":"traceutil/trace.go:171","msg":"trace[588811907] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2798; }","duration":"120.049572ms","start":"2026-10-09T02:13:14.232566Z","end":"2026-10-09T02:13:14.352615Z","steps":["trace[588811907] 'agreement among raft nodes before linearized reading' (duration: 119.926989ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:14.352543Z","caller":"traceutil/trace.go:171","msg":"trace[424396880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2798; }","duration":"438.298045ms","start":"2026-10-09T02:13:13.914227Z","end":"2026-10-09T02:13:14.352525Z","steps":["trace[424396880] 'agreement among raft nodes before linearized reading' (duration: 438.057281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:14.352677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.04172ms","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-10-09T02:13:14.352721Z","caller":"traceutil/trace.go:171","msg":"trace[228748165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2798; }","duration":"438.160593ms","start":"2026-10-09T02:13:13.91455Z","end":"2026-10-09T02:13:14.35271Z","steps":["trace[228748165] 'agreement among raft nodes before linearized reading' (duration: 438.066121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:14.352816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:13.914525Z","time spent":"438.280305ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-09T02:13:14.352708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:13.9142Z","time spent":"438.487599ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T02:13:14.352999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.301415ms","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-10-09T02:13:14.353114Z","caller":"traceutil/trace.go:171","msg":"trace[896026350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2798; }","duration":"438.447349ms","start":"2026-10-09T02:13:13.914645Z","end":"2026-10-09T02:13:14.353093Z","steps":["trace[896026350] 'agreement among raft nodes before linearized reading' (duration: 437.948938ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:14.353212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:13.914638Z","time spent":"438.561741ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T02:13:14.353362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.997369ms","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-10-09T02:13:14.353412Z","caller":"traceutil/trace.go:171","msg":"trace[1552282633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2798; }","duration":"438.860007ms","start":"2026-10-09T02:13:13.914537Z","end":"2026-10-09T02:13:14.353397Z","steps":["trace[1552282633] 'agreement among raft nodes before linearized reading' (duration: 437.941738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:14.35353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:13.914509Z","time spent":"439.008469ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-09T02:13:14.899622Z","caller":"traceutil/trace.go:171","msg":"trace[1861378590] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"540.978238ms","start":"2026-10-09T02:13:14.358607Z","end":"2026-10-09T02:13:14.899585Z","steps":["trace[1861378590] 'process raft request' (duration: 540.713423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:14.899944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:14.358589Z","time spent":"541.240843ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:14.900051Z","caller":"traceutil/trace.go:171","msg":"trace[1019303527] linearizableReadLoop","detail":"{readStateIndex:2909; appliedIndex:2906; }","duration":"455.863999ms","start":"2026-10-09T02:13:14.444158Z","end":"2026-10-09T02:13:14.900022Z","steps":["trace[1019303527] 'read index received' (duration: 455.153514ms)","trace[1019303527] 'applied index is now lower than readState.Index' (duration: 709.295µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:14.900182Z","caller":"traceutil/trace.go:171","msg":"trace[12215493] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"541.630681ms","start":"2026-10-09T02:13:14.358504Z","end":"2026-10-09T02:13:14.900135Z","steps":["trace[12215493] 'process raft request' (duration: 541.028899ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:14.900219Z","caller":"traceutil/trace.go:171","msg":"trace[30785302] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"539.917336ms","start":"2026-10-09T02:13:14.360284Z","end":"2026-10-09T02:13:14.900201Z","steps":["trace[30785302] 'process raft request' (duration: 539.590409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:14.900368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:14.358489Z","time spent":"541.767164ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:14.900371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:14.360239Z","time spent":"540.023168ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:14.900377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.218087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:13:14.900487Z","caller":"traceutil/trace.go:171","msg":"trace[1278449219] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2802; }","duration":"456.372449ms","start":"2026-10-09T02:13:14.444099Z","end":"2026-10-09T02:13:14.900472Z","steps":["trace[1278449219] 'agreement among raft nodes before linearized reading' (duration: 456.139165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:14.900561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:14.444073Z","time spent":"456.474472ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-10-09T02:13:15.072781Z","caller":"traceutil/trace.go:171","msg":"trace[1337352954] linearizableReadLoop","detail":"{readStateIndex:2911; appliedIndex:2909; }","duration":"172.60866ms","start":"2026-10-09T02:13:14.900141Z","end":"2026-10-09T02:13:15.07275Z","steps":["trace[1337352954] 'read index received' (duration: 97.762846ms)","trace[1337352954] 'applied index is now lower than readState.Index' (duration: 74.844664ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:15.072953Z","caller":"traceutil/trace.go:171","msg":"trace[324953623] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"301.651666ms","start":"2026-10-09T02:13:14.771285Z","end":"2026-10-09T02:13:15.072936Z","steps":["trace[324953623] 'process raft request' (duration: 301.403291ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:15.0728Z","caller":"traceutil/trace.go:171","msg":"trace[80098898] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"343.273925ms","start":"2026-10-09T02:13:14.729486Z","end":"2026-10-09T02:13:15.07276Z","steps":["trace[80098898] 'process raft request' (duration: 268.489172ms)","trace[80098898] 'compare' (duration: 74.530228ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:15.07309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:14.771258Z","time spent":"301.722098ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:15.073225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:14.729458Z","time spent":"343.602803ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:15.073296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.918366ms","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-10-09T02:13:15.073258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.644669ms","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-10-09T02:13:15.073349Z","caller":"traceutil/trace.go:171","msg":"trace[2082687452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2804; }","duration":"573.993608ms","start":"2026-10-09T02:13:14.49934Z","end":"2026-10-09T02:13:15.073334Z","steps":["trace[2082687452] 'agreement among raft nodes before linearized reading' (duration: 573.799984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:15.073387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:14.499331Z","time spent":"574.047229ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T02:13:15.0734Z","caller":"traceutil/trace.go:171","msg":"trace[1451075348] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2804; }","duration":"451.863134ms","start":"2026-10-09T02:13:14.621507Z","end":"2026-10-09T02:13:15.07337Z","steps":["trace[1451075348] 'agreement among raft nodes before linearized reading' (duration: 451.586568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:15.073488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:14.621484Z","time spent":"451.987107ms","remote":"127.0.0.1:58226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-09T02:13:15.637186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.67498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:15.63726Z","caller":"traceutil/trace.go:171","msg":"trace[918530334] linearizableReadLoop","detail":"{readStateIndex:2913; appliedIndex:2912; }","duration":"498.771449ms","start":"2026-10-09T02:13:15.138476Z","end":"2026-10-09T02:13:15.637247Z","steps":["trace[918530334] 'read index received' (duration: 74.885485ms)","trace[918530334] 'applied index is now lower than readState.Index' (duration: 423.885024ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:15.637602Z","caller":"traceutil/trace.go:171","msg":"trace[134900366] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"509.039508ms","start":"2026-10-09T02:13:15.128532Z","end":"2026-10-09T02:13:15.637571Z","steps":["trace[134900366] 'process raft request' (duration: 84.896069ms)","trace[134900366] 'compare' (duration: 423.448875ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:15.637803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:15.128494Z","time spent":"509.178131ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:15.637864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.36508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"warn","ts":"2026-10-09T02:13:15.637814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.025019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:15.637955Z","caller":"traceutil/trace.go:171","msg":"trace[1705575456] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2806; }","duration":"499.511763ms","start":"2026-10-09T02:13:15.138424Z","end":"2026-10-09T02:13:15.637935Z","steps":["trace[1705575456] 'agreement among raft nodes before linearized reading' (duration: 499.162006ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:15.637983Z","caller":"traceutil/trace.go:171","msg":"trace[2046609786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2806; }","duration":"185.230463ms","start":"2026-10-09T02:13:15.452731Z","end":"2026-10-09T02:13:15.637962Z","steps":["trace[2046609786] 'agreement among raft nodes before linearized reading' (duration: 185.018778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:15.638003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:15.138401Z","time spent":"499.595606ms","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":7486,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"warn","ts":"2026-10-09T02:13:15.638276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.448137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-09T02:13:15.638355Z","caller":"traceutil/trace.go:171","msg":"trace[1520157454] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2806; }","duration":"103.545939ms","start":"2026-10-09T02:13:15.534791Z","end":"2026-10-09T02:13:15.638337Z","steps":["trace[1520157454] 'agreement among raft nodes before linearized reading' (duration: 103.13341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:16.218073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154587819577313645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-09T02:13:16.7187Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154587819577313645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-09T02:13:17.219262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154587819577313645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-09T02:13:17.38659Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.678314568s","expected-duration":"1s"} {"level":"warn","ts":"2026-10-09T02:13:17.629821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.89222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:17.629933Z","caller":"traceutil/trace.go:171","msg":"trace[1161535241] transaction","detail":"{read_only:false; response_revision:2807; number_of_response:1; }","duration":"1.921780971s","start":"2026-10-09T02:13:15.708128Z","end":"2026-10-09T02:13:17.629909Z","steps":["trace[1161535241] 'process raft request' (duration: 1.678697846s)","trace[1161535241] 'compare' (duration: 242.437711ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:17.629998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:15.708092Z","time spent":"1.921871803s","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:13:17.668735Z","caller":"traceutil/trace.go:171","msg":"trace[1268495905] transaction","detail":"{read_only:false; response_revision:2808; number_of_response:1; }","duration":"1.307363661s","start":"2026-10-09T02:13:16.361342Z","end":"2026-10-09T02:13:17.668706Z","steps":["trace[1268495905] 'process raft request' (duration: 1.307204318s)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:17.66886Z","caller":"traceutil/trace.go:171","msg":"trace[964281301] linearizableReadLoop","detail":"{readStateIndex:2916; appliedIndex:2913; }","duration":"1.951333018s","start":"2026-10-09T02:13:15.717504Z","end":"2026-10-09T02:13:17.668837Z","steps":["trace[964281301] 'read index received' (duration: 1.669346395s)","trace[964281301] 'applied index is now lower than readState.Index' (duration: 281.984713ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:13:17.668883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:16.361308Z","time spent":"1.307490863s","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:17.668993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.95146835s","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-10-09T02:13:17.668995Z","caller":"traceutil/trace.go:171","msg":"trace[1214953663] transaction","detail":"{read_only:false; response_revision:2809; number_of_response:1; }","duration":"590.937143ms","start":"2026-10-09T02:13:17.078036Z","end":"2026-10-09T02:13:17.668973Z","steps":["trace[1214953663] 'process raft request' (duration: 590.631486ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:17.669029Z","caller":"traceutil/trace.go:171","msg":"trace[1592671821] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2809; }","duration":"1.951568593s","start":"2026-10-09T02:13:15.717446Z","end":"2026-10-09T02:13:17.669014Z","steps":["trace[1592671821] 'agreement among raft nodes before linearized reading' (duration: 1.951492161s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.669062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:15.717421Z","time spent":"1.951632483s","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-09T02:13:17.669101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:17.078015Z","time spent":"591.020094ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:13:17.69954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.662527ms","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-10-09T02:13:17.699593Z","caller":"traceutil/trace.go:171","msg":"trace[789208097] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2810; }","duration":"664.748898ms","start":"2026-10-09T02:13:17.034831Z","end":"2026-10-09T02:13:17.69958Z","steps":["trace[789208097] 'agreement among raft nodes before linearized reading' (duration: 664.583654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.699585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.590396ms","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":"warn","ts":"2026-10-09T02:13:17.699623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:17.034813Z","time spent":"664.80301ms","remote":"127.0.0.1:35396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-10-09T02:13:17.699648Z","caller":"traceutil/trace.go:171","msg":"trace[438082488] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2810; }","duration":"553.679667ms","start":"2026-10-09T02:13:17.145953Z","end":"2026-10-09T02:13:17.699632Z","steps":["trace[438082488] 'agreement among raft nodes before linearized reading' (duration: 553.588005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.699671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.475272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-09T02:13:17.69969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:17.145942Z","time spent":"553.737438ms","remote":"127.0.0.1:46698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-10-09T02:13:17.699708Z","caller":"traceutil/trace.go:171","msg":"trace[210631857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2810; }","duration":"793.564873ms","start":"2026-10-09T02:13:16.906131Z","end":"2026-10-09T02:13:17.699695Z","steps":["trace[210631857] 'agreement among raft nodes before linearized reading' (duration: 793.485912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.699746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:16.906104Z","time spent":"793.634914ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T02:13:17.699833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.475096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-09T02:13:17.699871Z","caller":"traceutil/trace.go:171","msg":"trace[1129783732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2810; }","duration":"561.547128ms","start":"2026-10-09T02:13:17.138313Z","end":"2026-10-09T02:13:17.69986Z","steps":["trace[1129783732] 'agreement among raft nodes before linearized reading' (duration: 561.457166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.699887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.784657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-09T02:13:17.699895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:17.138301Z","time spent":"561.587938ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-09T02:13:17.699897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.294388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-09T02:13:17.699917Z","caller":"traceutil/trace.go:171","msg":"trace[822409899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2810; }","duration":"793.849029ms","start":"2026-10-09T02:13:16.906058Z","end":"2026-10-09T02:13:17.699907Z","steps":["trace[822409899] 'agreement among raft nodes before linearized reading' (duration: 793.760617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.699959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:16.906034Z","time spent":"793.918371ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T02:13:17.700035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114535542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:17.700066Z","caller":"traceutil/trace.go:171","msg":"trace[544612419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2810; }","duration":"1.114603844s","start":"2026-10-09T02:13:16.585451Z","end":"2026-10-09T02:13:17.700054Z","steps":["trace[544612419] 'agreement among raft nodes before linearized reading' (duration: 1.114563263s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.700088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:16.585428Z","time spent":"1.114654835s","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T02:13:17.699925Z","caller":"traceutil/trace.go:171","msg":"trace[1150173378] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:2810; }","duration":"395.373691ms","start":"2026-10-09T02:13:17.304542Z","end":"2026-10-09T02:13:17.699915Z","steps":["trace[1150173378] 'agreement among raft nodes before linearized reading' (duration: 395.284089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.700223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:17.304517Z","time spent":"395.693557ms","remote":"127.0.0.1:35212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"warn","ts":"2026-10-09T02:13:17.699586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.369109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-09T02:13:17.700265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282237779s","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-10-09T02:13:17.700298Z","caller":"traceutil/trace.go:171","msg":"trace[681337201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2810; }","duration":"1.28231388s","start":"2026-10-09T02:13:16.417974Z","end":"2026-10-09T02:13:17.700288Z","steps":["trace[681337201] 'agreement among raft nodes before linearized reading' (duration: 1.282231169s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.700321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:16.417951Z","time spent":"1.282364672s","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T02:13:17.700328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.803960253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:17.700377Z","caller":"traceutil/trace.go:171","msg":"trace[639482182] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2810; }","duration":"1.804097226s","start":"2026-10-09T02:13:15.896266Z","end":"2026-10-09T02:13:17.700363Z","steps":["trace[639482182] 'agreement among raft nodes before linearized reading' (duration: 1.804013024s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.70041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:15.896239Z","time spent":"1.804162418s","remote":"127.0.0.1:35420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-10-09T02:13:17.700395Z","caller":"traceutil/trace.go:171","msg":"trace[254775693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2810; }","duration":"794.221407ms","start":"2026-10-09T02:13:16.906128Z","end":"2026-10-09T02:13:17.70035Z","steps":["trace[254775693] 'agreement among raft nodes before linearized reading' (duration: 793.340109ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.700633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:13:16.906096Z","time spent":"794.454161ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-09T02:13:17.988444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.133134ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-10-09T02:13:17.988956Z","caller":"traceutil/trace.go:171","msg":"trace[1429705835] linearizableReadLoop","detail":"{readStateIndex:2918; appliedIndex:2917; }","duration":"286.712969ms","start":"2026-10-09T02:13:17.702188Z","end":"2026-10-09T02:13:17.988901Z","steps":["trace[1429705835] 'read index received' (duration: 124.089424ms)","trace[1429705835] 'applied index is now lower than readState.Index' (duration: 162.618365ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:17.989003Z","caller":"traceutil/trace.go:171","msg":"trace[97255617] transaction","detail":"{read_only:false; response_revision:2811; number_of_response:1; }","duration":"282.83598ms","start":"2026-10-09T02:13:17.706133Z","end":"2026-10-09T02:13:17.988969Z","steps":["trace[97255617] 'process raft request' (duration: 282.618536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.989808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.019635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-09T02:13:17.989843Z","caller":"traceutil/trace.go:171","msg":"trace[862084517] transaction","detail":"{read_only:false; response_revision:2814; number_of_response:1; }","duration":"283.007464ms","start":"2026-10-09T02:13:17.706819Z","end":"2026-10-09T02:13:17.989827Z","steps":["trace[862084517] 'process raft request' (duration: 282.431852ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:17.989861Z","caller":"traceutil/trace.go:171","msg":"trace[2007775102] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2815; }","duration":"286.115948ms","start":"2026-10-09T02:13:17.703732Z","end":"2026-10-09T02:13:17.989848Z","steps":["trace[2007775102] 'agreement among raft nodes before linearized reading' (duration: 285.876383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.990248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.399113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1449"} {"level":"info","ts":"2026-10-09T02:13:17.990313Z","caller":"traceutil/trace.go:171","msg":"trace[1250426443] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2815; }","duration":"286.509875ms","start":"2026-10-09T02:13:17.703787Z","end":"2026-10-09T02:13:17.990297Z","steps":["trace[1250426443] 'agreement among raft nodes before linearized reading' (duration: 286.2778ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:17.990372Z","caller":"traceutil/trace.go:171","msg":"trace[304656007] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"281.35289ms","start":"2026-10-09T02:13:17.709006Z","end":"2026-10-09T02:13:17.990359Z","steps":["trace[304656007] 'process raft request' (duration: 280.461982ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:17.989331Z","caller":"traceutil/trace.go:171","msg":"trace[2072975342] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"282.82818ms","start":"2026-10-09T02:13:17.706462Z","end":"2026-10-09T02:13:17.989291Z","steps":["trace[2072975342] 'process raft request' (duration: 282.627756ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:17.989309Z","caller":"traceutil/trace.go:171","msg":"trace[1092685930] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"282.934193ms","start":"2026-10-09T02:13:17.706349Z","end":"2026-10-09T02:13:17.989284Z","steps":["trace[1092685930] 'process raft request' (duration: 282.563926ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:17.989543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.343771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:17.991425Z","caller":"traceutil/trace.go:171","msg":"trace[484422095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2814; }","duration":"289.514276ms","start":"2026-10-09T02:13:17.701892Z","end":"2026-10-09T02:13:17.991406Z","steps":["trace[484422095] 'agreement among raft nodes before linearized reading' (duration: 287.240399ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:24.555837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.529987ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:13:24.555965Z","caller":"traceutil/trace.go:171","msg":"trace[1115181601] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2849; }","duration":"133.67964ms","start":"2026-10-09T02:13:24.422265Z","end":"2026-10-09T02:13:24.555944Z","steps":["trace[1115181601] 'range keys from in-memory index tree' (duration: 133.510767ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:13:50.46178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.562203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:13:50.46233Z","caller":"traceutil/trace.go:171","msg":"trace[1292297745] transaction","detail":"{read_only:false; response_revision:2991; number_of_response:1; }","duration":"288.884996ms","start":"2026-10-09T02:13:50.173409Z","end":"2026-10-09T02:13:50.462294Z","steps":["trace[1292297745] 'process raft request' (duration: 142.6928ms)","trace[1292297745] 'compare' (duration: 145.341279ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:13:50.46241Z","caller":"traceutil/trace.go:171","msg":"trace[426287207] transaction","detail":"{read_only:false; response_revision:2992; number_of_response:1; }","duration":"287.596531ms","start":"2026-10-09T02:13:50.174795Z","end":"2026-10-09T02:13:50.462392Z","steps":["trace[426287207] 'process raft request' (duration: 287.456909ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:50.462955Z","caller":"traceutil/trace.go:171","msg":"trace[1224159584] transaction","detail":"{read_only:false; response_revision:2993; number_of_response:1; }","duration":"287.45784ms","start":"2026-10-09T02:13:50.175474Z","end":"2026-10-09T02:13:50.462932Z","steps":["trace[1224159584] 'process raft request' (duration: 286.884109ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:50.463562Z","caller":"traceutil/trace.go:171","msg":"trace[1483477423] transaction","detail":"{read_only:false; response_revision:2994; number_of_response:1; }","duration":"287.989909ms","start":"2026-10-09T02:13:50.175554Z","end":"2026-10-09T02:13:50.463544Z","steps":["trace[1483477423] 'process raft request' (duration: 287.306676ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:56.077685Z","caller":"traceutil/trace.go:171","msg":"trace[1297531720] transaction","detail":"{read_only:false; response_revision:3017; number_of_response:1; }","duration":"254.872065ms","start":"2026-10-09T02:13:55.822786Z","end":"2026-10-09T02:13:56.077658Z","steps":["trace[1297531720] 'process raft request' (duration: 254.5691ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:56.088751Z","caller":"traceutil/trace.go:171","msg":"trace[1164009108] transaction","detail":"{read_only:false; response_revision:3019; number_of_response:1; }","duration":"242.498215ms","start":"2026-10-09T02:13:55.846232Z","end":"2026-10-09T02:13:56.088731Z","steps":["trace[1164009108] 'process raft request' (duration: 242.428733ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:13:56.088759Z","caller":"traceutil/trace.go:171","msg":"trace[1141470753] transaction","detail":"{read_only:false; response_revision:3018; number_of_response:1; }","duration":"265.782227ms","start":"2026-10-09T02:13:55.822943Z","end":"2026-10-09T02:13:56.088725Z","steps":["trace[1141470753] 'process raft request' (duration: 265.629044ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:18:10.924287Z","caller":"traceutil/trace.go:171","msg":"trace[476222409] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"105.96081ms","start":"2026-10-09T02:18:10.818302Z","end":"2026-10-09T02:18:10.924263Z","steps":["trace[476222409] 'process raft request' (duration: 105.843418ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:18:10.935575Z","caller":"traceutil/trace.go:171","msg":"trace[2076375952] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"112.069017ms","start":"2026-10-09T02:18:10.823479Z","end":"2026-10-09T02:18:10.935548Z","steps":["trace[2076375952] 'process raft request' (duration: 111.791243ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:18:14.359871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2797} {"level":"info","ts":"2026-10-09T02:18:14.455032Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2797,"took":"93.823087ms","hash":4042038200,"current-db-size-bytes":51167232,"current-db-size":"51 MB","current-db-size-in-use-bytes":25948160,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T02:18:14.455161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4042038200,"revision":2797,"compact-revision":-1} {"level":"warn","ts":"2026-10-09T02:18:23.911594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.275037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:18:23.911896Z","caller":"traceutil/trace.go:171","msg":"trace[2139185166] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"242.615648ms","start":"2026-10-09T02:18:23.669248Z","end":"2026-10-09T02:18:23.911864Z","steps":["trace[2139185166] 'process raft request' (duration: 69.993686ms)","trace[2139185166] 'compare' (duration: 171.81615ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:18:24.084838Z","caller":"traceutil/trace.go:171","msg":"trace[1655582042] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"168.591545ms","start":"2026-10-09T02:18:23.916215Z","end":"2026-10-09T02:18:24.084807Z","steps":["trace[1655582042] 'process raft request' (duration: 128.579421ms)","trace[1655582042] 'compare' (duration: 39.871162ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:18:24.109914Z","caller":"traceutil/trace.go:171","msg":"trace[1145310739] linearizableReadLoop","detail":"{readStateIndex:5340; appliedIndex:5336; }","duration":"192.225477ms","start":"2026-10-09T02:18:23.917652Z","end":"2026-10-09T02:18:24.109877Z","steps":["trace[1145310739] 'read index received' (duration: 127.1565ms)","trace[1145310739] 'applied index is now lower than readState.Index' (duration: 65.067547ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:18:24.109958Z","caller":"traceutil/trace.go:171","msg":"trace[1400145790] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"193.494286ms","start":"2026-10-09T02:18:23.916416Z","end":"2026-10-09T02:18:24.109911Z","steps":["trace[1400145790] 'process raft request' (duration: 193.13167ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:18:24.109996Z","caller":"traceutil/trace.go:171","msg":"trace[728946586] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"192.941898ms","start":"2026-10-09T02:18:23.917026Z","end":"2026-10-09T02:18:24.109968Z","steps":["trace[728946586] 'process raft request' (duration: 192.744625ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:18:24.11005Z","caller":"traceutil/trace.go:171","msg":"trace[1651519359] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"192.591143ms","start":"2026-10-09T02:18:23.91742Z","end":"2026-10-09T02:18:24.110011Z","steps":["trace[1651519359] 'process raft request' (duration: 192.40037ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:18:24.110274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.083936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1652"} {"level":"warn","ts":"2026-10-09T02:18:24.110274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.598373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1652"} {"level":"info","ts":"2026-10-09T02:18:24.110332Z","caller":"traceutil/trace.go:171","msg":"trace[2039346305] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5139; }","duration":"182.187076ms","start":"2026-10-09T02:18:23.928122Z","end":"2026-10-09T02:18:24.110309Z","steps":["trace[2039346305] 'agreement among raft nodes before linearized reading' (duration: 182.053135ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:18:24.110332Z","caller":"traceutil/trace.go:171","msg":"trace[965984516] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5139; }","duration":"192.688505ms","start":"2026-10-09T02:18:23.917628Z","end":"2026-10-09T02:18:24.110316Z","steps":["trace[965984516] 'agreement among raft nodes before linearized reading' (duration: 192.439731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:18:24.566245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.560856ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:18:24.566443Z","caller":"traceutil/trace.go:171","msg":"trace[1407579383] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5147; }","duration":"144.8039ms","start":"2026-10-09T02:18:24.421613Z","end":"2026-10-09T02:18:24.566417Z","steps":["trace[1407579383] 'range keys from in-memory index tree' (duration: 144.543125ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:18:24.567043Z","caller":"traceutil/trace.go:171","msg":"trace[1937674174] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"274.414026ms","start":"2026-10-09T02:18:24.292606Z","end":"2026-10-09T02:18:24.56702Z","steps":["trace[1937674174] 'process raft request' (duration: 267.370826ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:18:24.769699Z","caller":"traceutil/trace.go:171","msg":"trace[792741165] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"113.762023ms","start":"2026-10-09T02:18:24.655903Z","end":"2026-10-09T02:18:24.769665Z","steps":["trace[792741165] 'process raft request' (duration: 113.51859ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:18:27.324754Z","caller":"traceutil/trace.go:171","msg":"trace[690461128] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"152.430816ms","start":"2026-10-09T02:18:27.172298Z","end":"2026-10-09T02:18:27.324728Z","steps":["trace[690461128] 'process raft request' (duration: 152.268474ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:18:27.339192Z","caller":"traceutil/trace.go:171","msg":"trace[2014120518] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"110.295762ms","start":"2026-10-09T02:18:27.228869Z","end":"2026-10-09T02:18:27.339165Z","steps":["trace[2014120518] 'process raft request' (duration: 109.597452ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:18:38.322452Z","caller":"traceutil/trace.go:171","msg":"trace[1956994083] linearizableReadLoop","detail":"{readStateIndex:5582; appliedIndex:5581; }","duration":"144.886071ms","start":"2026-10-09T02:18:38.177548Z","end":"2026-10-09T02:18:38.322434Z","steps":["trace[1956994083] 'read index received' (duration: 144.718549ms)","trace[1956994083] 'applied index is now lower than readState.Index' (duration: 166.552µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:18:38.322569Z","caller":"traceutil/trace.go:171","msg":"trace[1799900187] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"173.227519ms","start":"2026-10-09T02:18:38.149314Z","end":"2026-10-09T02:18:38.322541Z","steps":["trace[1799900187] 'process raft request' (duration: 172.991905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:18:38.322649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.082554ms","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-10-09T02:18:38.322692Z","caller":"traceutil/trace.go:171","msg":"trace[636746560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5375; }","duration":"145.161305ms","start":"2026-10-09T02:18:38.177521Z","end":"2026-10-09T02:18:38.322682Z","steps":["trace[636746560] 'agreement among raft nodes before linearized reading' (duration: 145.034233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:18:38.322874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.686968ms","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-10-09T02:18:38.322908Z","caller":"traceutil/trace.go:171","msg":"trace[776780768] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5375; }","duration":"124.761729ms","start":"2026-10-09T02:18:38.198137Z","end":"2026-10-09T02:18:38.322898Z","steps":["trace[776780768] 'agreement among raft nodes before linearized reading' (duration: 124.656887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:18:38.322886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.231432ms","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-10-09T02:18:38.322958Z","caller":"traceutil/trace.go:171","msg":"trace[1564830183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5375; }","duration":"144.359273ms","start":"2026-10-09T02:18:38.178581Z","end":"2026-10-09T02:18:38.32294Z","steps":["trace[1564830183] 'agreement among raft nodes before linearized reading' (duration: 144.14343ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:18:38.440131Z","caller":"traceutil/trace.go:171","msg":"trace[562549287] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"102.474076ms","start":"2026-10-09T02:18:38.337636Z","end":"2026-10-09T02:18:38.44011Z","steps":["trace[562549287] 'process raft request' (duration: 102.323804ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:19:07.514945Z","caller":"traceutil/trace.go:171","msg":"trace[590996798] transaction","detail":"{read_only:false; response_revision:5655; number_of_response:1; }","duration":"141.781343ms","start":"2026-10-09T02:19:07.373138Z","end":"2026-10-09T02:19:07.514919Z","steps":["trace[590996798] 'process raft request' (duration: 141.622721ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:19:17.882948Z","caller":"traceutil/trace.go:171","msg":"trace[1268061921] transaction","detail":"{read_only:false; response_revision:5715; number_of_response:1; }","duration":"100.848ms","start":"2026-10-09T02:19:17.782077Z","end":"2026-10-09T02:19:17.882925Z","steps":["trace[1268061921] 'process raft request' (duration: 91.010534ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:19:29.731153Z","caller":"traceutil/trace.go:171","msg":"trace[1653193112] transaction","detail":"{read_only:false; response_revision:5805; number_of_response:1; }","duration":"110.697201ms","start":"2026-10-09T02:19:29.620432Z","end":"2026-10-09T02:19:29.731129Z","steps":["trace[1653193112] 'process raft request' (duration: 110.63646ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:19:29.731588Z","caller":"traceutil/trace.go:171","msg":"trace[749498189] transaction","detail":"{read_only:false; response_revision:5804; number_of_response:1; }","duration":"119.359822ms","start":"2026-10-09T02:19:29.612179Z","end":"2026-10-09T02:19:29.731539Z","steps":["trace[749498189] 'process raft request' (duration: 118.849225ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:19:29.731406Z","caller":"traceutil/trace.go:171","msg":"trace[1321984491] transaction","detail":"{read_only:false; response_revision:5803; number_of_response:1; }","duration":"119.356442ms","start":"2026-10-09T02:19:29.612022Z","end":"2026-10-09T02:19:29.731378Z","steps":["trace[1321984491] 'process raft request' (duration: 118.873025ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:22:41.8684Z","caller":"traceutil/trace.go:171","msg":"trace[1124513310] linearizableReadLoop","detail":"{readStateIndex:7410; appliedIndex:7409; }","duration":"128.168176ms","start":"2026-10-09T02:22:41.740209Z","end":"2026-10-09T02:22:41.868377Z","steps":["trace[1124513310] 'read index received' (duration: 128.058824ms)","trace[1124513310] 'applied index is now lower than readState.Index' (duration: 108.352µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:22:41.868454Z","caller":"traceutil/trace.go:171","msg":"trace[679233050] transaction","detail":"{read_only:false; response_revision:7144; number_of_response:1; }","duration":"147.577321ms","start":"2026-10-09T02:22:41.720838Z","end":"2026-10-09T02:22:41.868415Z","steps":["trace[679233050] 'process raft request' (duration: 147.390767ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:22:41.868793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.532393ms","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-10-09T02:22:41.868965Z","caller":"traceutil/trace.go:171","msg":"trace[2115146324] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7144; }","duration":"128.774067ms","start":"2026-10-09T02:22:41.740179Z","end":"2026-10-09T02:22:41.868953Z","steps":["trace[2115146324] 'agreement among raft nodes before linearized reading' (duration: 128.337049ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:22:42.009361Z","caller":"traceutil/trace.go:171","msg":"trace[248256566] transaction","detail":"{read_only:false; response_revision:7145; number_of_response:1; }","duration":"241.2302ms","start":"2026-10-09T02:22:41.768092Z","end":"2026-10-09T02:22:42.009322Z","steps":["trace[248256566] 'process raft request' (duration: 203.473531ms)","trace[248256566] 'compare' (duration: 37.537335ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:22:42.014514Z","caller":"traceutil/trace.go:171","msg":"trace[1652685976] transaction","detail":"{read_only:false; response_revision:7146; number_of_response:1; }","duration":"140.325111ms","start":"2026-10-09T02:22:41.874163Z","end":"2026-10-09T02:22:42.014488Z","steps":["trace[1652685976] 'process raft request' (duration: 140.191468ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:23:14.375667Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4994} {"level":"info","ts":"2026-10-09T02:23:14.486902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4994,"took":"109.510234ms","hash":1120630063,"current-db-size-bytes":51167232,"current-db-size":"51 MB","current-db-size-in-use-bytes":19742720,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-10-09T02:23:14.486988Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1120630063,"revision":4994,"compact-revision":2797}