{"level":"warn","ts":"2026-02-19T02:52:12.423809Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-19T02:52:12.423892Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-02-19T02:52:12.423964Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-19T02:52:12.423973Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-19T02:52:12.423994Z","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-02-19T02:52:12.424597Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-02-19T02:52:12.42467Z","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":16,"max-cpu-available":16,"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.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-02-19T02:52:12.517303Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"92.402987ms"} {"level":"info","ts":"2026-02-19T02:52:12.713354Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-02-19T02:52:12.713551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T02:52:12.713581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-02-19T02:52:12.713598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T02:52:12.713609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-02-19T02:52:12.71367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-02-19T02:52:12.77059Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T02:52:12.776347Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T02:52:12.806622Z","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-02-19T02:52:12.853009Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T02:52:12.8532Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T02:52:12.853448Z","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-02-19T02:52:12.864143Z","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-02-19T02:52:12.864157Z","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-02-19T02:52:12.864706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-02-19T02:52:12.864964Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-19T02:52:12.871317Z","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-02-19T02:52:12.871391Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-19T02:52:12.871419Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-19T02:52:12.871531Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T02:52:12.871557Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T02:52:13.714143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T02:52:13.714294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T02:52:13.714358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-02-19T02:52:13.714401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-02-19T02:52:13.714445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-19T02:52:13.714484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-02-19T02:52:13.714525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-19T02:52:13.790772Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T02:52:13.79081Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:52:13.790839Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:52:13.790909Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:52:13.791299Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T02:52:13.791345Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T02:52:13.793652Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T02:52:13.794476Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-02-19T02:52:13.79646Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:52:13.796794Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:52:13.799639Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:52:19.454813Z","caller":"traceutil/trace.go:171","msg":"trace[1366831772] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:259; }","duration":"154.784822ms","start":"2026-02-19T02:52:19.300003Z","end":"2026-02-19T02:52:19.454788Z","steps":["trace[1366831772] 'read index received' (duration: 84.730698ms)","trace[1366831772] 'applied index is now lower than readState.Index' (duration: 70.052764ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:19.454954Z","caller":"traceutil/trace.go:171","msg":"trace[271202716] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"164.578146ms","start":"2026-02-19T02:52:19.290363Z","end":"2026-02-19T02:52:19.454941Z","steps":["trace[271202716] 'process raft request' (duration: 94.374579ms)","trace[271202716] 'compare' (duration: 69.673857ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:19.455201Z","caller":"traceutil/trace.go:171","msg":"trace[1459890172] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"162.582054ms","start":"2026-02-19T02:52:19.292589Z","end":"2026-02-19T02:52:19.455171Z","steps":["trace[1459890172] 'process raft request' (duration: 162.140775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:19.455197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.126669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:19.455463Z","caller":"traceutil/trace.go:171","msg":"trace[1098420112] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:251; }","duration":"155.375745ms","start":"2026-02-19T02:52:19.29998Z","end":"2026-02-19T02:52:19.455356Z","steps":["trace[1098420112] 'agreement among raft nodes before linearized reading' (duration: 155.077049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:19.938178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.888911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3952"} {"level":"warn","ts":"2026-02-19T02:52:19.938375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.515999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:19.938389Z","caller":"traceutil/trace.go:171","msg":"trace[473477403] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:264; }","duration":"126.130078ms","start":"2026-02-19T02:52:19.812227Z","end":"2026-02-19T02:52:19.938357Z","steps":["trace[473477403] 'range keys from in-memory index tree' (duration: 125.731868ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:19.938434Z","caller":"traceutil/trace.go:171","msg":"trace[1129175438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:264; }","duration":"103.60396ms","start":"2026-02-19T02:52:19.834813Z","end":"2026-02-19T02:52:19.938417Z","steps":["trace[1129175438] 'range keys from in-memory index tree' (duration: 103.461458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:19.938629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.858454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-19T02:52:19.938704Z","caller":"traceutil/trace.go:171","msg":"trace[1032784277] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:264; }","duration":"127.993617ms","start":"2026-02-19T02:52:19.810696Z","end":"2026-02-19T02:52:19.93869Z","steps":["trace[1032784277] 'range keys from in-memory index tree' (duration: 127.742611ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:20.035221Z","caller":"traceutil/trace.go:171","msg":"trace[4781804] transaction","detail":"{read_only:false; number_of_response:0; response_revision:264; }","duration":"189.636356ms","start":"2026-02-19T02:52:19.845565Z","end":"2026-02-19T02:52:20.035201Z","steps":["trace[4781804] 'process raft request' (duration: 189.574735ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:20.035238Z","caller":"traceutil/trace.go:171","msg":"trace[1628468670] transaction","detail":"{read_only:false; number_of_response:0; response_revision:264; }","duration":"189.680927ms","start":"2026-02-19T02:52:19.845538Z","end":"2026-02-19T02:52:20.035219Z","steps":["trace[1628468670] 'process raft request' (duration: 189.574815ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:20.035284Z","caller":"traceutil/trace.go:171","msg":"trace[1774801389] transaction","detail":"{read_only:false; number_of_response:0; response_revision:264; }","duration":"189.778779ms","start":"2026-02-19T02:52:19.845474Z","end":"2026-02-19T02:52:20.035253Z","steps":["trace[1774801389] 'process raft request' (duration: 189.606876ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:20.035212Z","caller":"traceutil/trace.go:171","msg":"trace[207852612] transaction","detail":"{read_only:false; number_of_response:0; response_revision:264; }","duration":"189.865341ms","start":"2026-02-19T02:52:19.845303Z","end":"2026-02-19T02:52:20.035168Z","steps":["trace[207852612] 'process raft request' (duration: 189.694617ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:20.103103Z","caller":"traceutil/trace.go:171","msg":"trace[80606922] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"131.807926ms","start":"2026-02-19T02:52:19.971273Z","end":"2026-02-19T02:52:20.103081Z","steps":["trace[80606922] 'process raft request' (duration: 131.678233ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:20.103153Z","caller":"traceutil/trace.go:171","msg":"trace[1934306496] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"160.647004ms","start":"2026-02-19T02:52:19.94248Z","end":"2026-02-19T02:52:20.103127Z","steps":["trace[1934306496] 'process raft request' (duration: 160.305607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:20.103163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.577221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-19T02:52:20.103344Z","caller":"traceutil/trace.go:171","msg":"trace[1848208903] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:266; }","duration":"145.795466ms","start":"2026-02-19T02:52:19.957527Z","end":"2026-02-19T02:52:20.103322Z","steps":["trace[1848208903] 'agreement among raft nodes before linearized reading' (duration: 145.540611ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:23.543213Z","caller":"traceutil/trace.go:171","msg":"trace[1257405838] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"134.293317ms","start":"2026-02-19T02:52:23.408894Z","end":"2026-02-19T02:52:23.543187Z","steps":["trace[1257405838] 'read index received' (duration: 133.976921ms)","trace[1257405838] 'applied index is now lower than readState.Index' (duration: 315.526µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:23.543278Z","caller":"traceutil/trace.go:171","msg":"trace[853843681] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"135.550663ms","start":"2026-02-19T02:52:23.407698Z","end":"2026-02-19T02:52:23.543249Z","steps":["trace[853843681] 'process raft request' (duration: 135.198626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:23.543502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.552072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:23.543685Z","caller":"traceutil/trace.go:171","msg":"trace[1906061709] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:301; }","duration":"134.786499ms","start":"2026-02-19T02:52:23.408865Z","end":"2026-02-19T02:52:23.543652Z","steps":["trace[1906061709] 'agreement among raft nodes before linearized reading' (duration: 134.481841ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:28.976788Z","caller":"traceutil/trace.go:171","msg":"trace[1354043838] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"272.992905ms","start":"2026-02-19T02:52:28.703764Z","end":"2026-02-19T02:52:28.976757Z","steps":["trace[1354043838] 'process raft request' (duration: 272.818562ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:34.392446Z","caller":"traceutil/trace.go:171","msg":"trace[1109884034] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"206.068187ms","start":"2026-02-19T02:52:34.186345Z","end":"2026-02-19T02:52:34.392413Z","steps":["trace[1109884034] 'process raft request' (duration: 205.845832ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:39.600842Z","caller":"traceutil/trace.go:171","msg":"trace[2107604959] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"151.401452ms","start":"2026-02-19T02:52:39.449414Z","end":"2026-02-19T02:52:39.600815Z","steps":["trace[2107604959] 'process raft request' (duration: 151.239309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:39.840351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.363261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T02:52:39.840462Z","caller":"traceutil/trace.go:171","msg":"trace[1484998543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"137.531004ms","start":"2026-02-19T02:52:39.702905Z","end":"2026-02-19T02:52:39.840436Z","steps":["trace[1484998543] 'range keys from in-memory index tree' (duration: 137.268939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:39.840603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.395583ms","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-02-19T02:52:39.840664Z","caller":"traceutil/trace.go:171","msg":"trace[600724477] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:426; }","duration":"139.500715ms","start":"2026-02-19T02:52:39.701149Z","end":"2026-02-19T02:52:39.840649Z","steps":["trace[600724477] 'range keys from in-memory index tree' (duration: 139.258521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:39.840707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.76585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-19T02:52:39.841452Z","caller":"traceutil/trace.go:171","msg":"trace[839900187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"138.551466ms","start":"2026-02-19T02:52:39.702841Z","end":"2026-02-19T02:52:39.841392Z","steps":["trace[839900187] 'range keys from in-memory index tree' (duration: 137.622827ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:40.723678Z","caller":"traceutil/trace.go:171","msg":"trace[58950511] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"296.76287ms","start":"2026-02-19T02:52:40.42689Z","end":"2026-02-19T02:52:40.723653Z","steps":["trace[58950511] 'process raft request' (duration: 296.589446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:51.105894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.131691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7mws5\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-19T02:52:51.105977Z","caller":"traceutil/trace.go:171","msg":"trace[1885731971] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7mws5; range_end:; response_count:1; response_revision:514; }","duration":"184.267714ms","start":"2026-02-19T02:52:50.921692Z","end":"2026-02-19T02:52:51.10596Z","steps":["trace[1885731971] 'range keys from in-memory index tree' (duration: 183.923556ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:51.81365Z","caller":"traceutil/trace.go:171","msg":"trace[1655408738] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"436.030764ms","start":"2026-02-19T02:52:51.377586Z","end":"2026-02-19T02:52:51.813617Z","steps":["trace[1655408738] 'read index received' (duration: 435.794329ms)","trace[1655408738] 'applied index is now lower than readState.Index' (duration: 235.565µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:51.81376Z","caller":"traceutil/trace.go:171","msg":"trace[1899154581] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"693.796525ms","start":"2026-02-19T02:52:51.119954Z","end":"2026-02-19T02:52:51.81375Z","steps":["trace[1899154581] 'process raft request' (duration: 693.490769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:51.813943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.345592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:51.814032Z","caller":"traceutil/trace.go:171","msg":"trace[441594739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"436.474754ms","start":"2026-02-19T02:52:51.377537Z","end":"2026-02-19T02:52:51.814012Z","steps":["trace[441594739] 'agreement among raft nodes before linearized reading' (duration: 436.349121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:51.813943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.63273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:52:51.814085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:51.377509Z","time spent":"436.568606ms","remote":"127.0.0.1:50856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T02:52:51.81418Z","caller":"traceutil/trace.go:171","msg":"trace[1589021488] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:515; }","duration":"112.924787ms","start":"2026-02-19T02:52:51.70123Z","end":"2026-02-19T02:52:51.814155Z","steps":["trace[1589021488] 'agreement among raft nodes before linearized reading' (duration: 112.58574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:51.814387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:51.119924Z","time spent":"693.874057ms","remote":"127.0.0.1:55558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:52:52.827269Z","caller":"traceutil/trace.go:171","msg":"trace[1306458411] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"919.682129ms","start":"2026-02-19T02:52:51.907562Z","end":"2026-02-19T02:52:52.827244Z","steps":["trace[1306458411] 'process raft request' (duration: 919.514165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:52.827447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:51.907546Z","time spent":"919.823192ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:52:52.833573Z","caller":"traceutil/trace.go:171","msg":"trace[1189008773] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"923.870061ms","start":"2026-02-19T02:52:51.909644Z","end":"2026-02-19T02:52:52.833514Z","steps":["trace[1189008773] 'process raft request' (duration: 921.606231ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:52.833586Z","caller":"traceutil/trace.go:171","msg":"trace[1701122410] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"923.774469ms","start":"2026-02-19T02:52:51.909778Z","end":"2026-02-19T02:52:52.833552Z","steps":["trace[1701122410] 'process raft request' (duration: 923.571424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:52.833746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:51.90974Z","time spent":"923.917202ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:52:52.83375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:51.909617Z","time spent":"924.031785ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:52:54.180041Z","caller":"traceutil/trace.go:171","msg":"trace[572542557] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"103.961591ms","start":"2026-02-19T02:52:54.076057Z","end":"2026-02-19T02:52:54.180019Z","steps":["trace[572542557] 'process raft request' (duration: 103.631274ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:57.138115Z","caller":"traceutil/trace.go:171","msg":"trace[1091450132] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:727; }","duration":"157.335116ms","start":"2026-02-19T02:52:56.98075Z","end":"2026-02-19T02:52:57.138085Z","steps":["trace[1091450132] 'read index received' (duration: 24.510934ms)","trace[1091450132] 'applied index is now lower than readState.Index' (duration: 132.822492ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:57.138249Z","caller":"traceutil/trace.go:171","msg":"trace[2055507077] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"167.827485ms","start":"2026-02-19T02:52:56.970357Z","end":"2026-02-19T02:52:57.138184Z","steps":["trace[2055507077] 'process raft request' (duration: 163.040881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:57.1385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.728496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"warn","ts":"2026-02-19T02:52:57.138546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.679137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-19T02:52:57.138593Z","caller":"traceutil/trace.go:171","msg":"trace[851819312] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"157.832888ms","start":"2026-02-19T02:52:56.980736Z","end":"2026-02-19T02:52:57.138569Z","steps":["trace[851819312] 'agreement among raft nodes before linearized reading' (duration: 157.49208ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:57.138605Z","caller":"traceutil/trace.go:171","msg":"trace[351155814] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:705; }","duration":"147.781078ms","start":"2026-02-19T02:52:56.990812Z","end":"2026-02-19T02:52:57.138593Z","steps":["trace[351155814] 'agreement among raft nodes before linearized reading' (duration: 147.444671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:57.771623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.690185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-19T02:52:57.771711Z","caller":"traceutil/trace.go:171","msg":"trace[843254217] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:708; }","duration":"190.854199ms","start":"2026-02-19T02:52:57.580833Z","end":"2026-02-19T02:52:57.771688Z","steps":["trace[843254217] 'range keys from in-memory index tree' (duration: 190.43461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:53:12.882696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674848113897452,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:53:13.389086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.14026ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T02:53:13.38929Z","caller":"traceutil/trace.go:171","msg":"trace[1220007791] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:907; }","duration":"1.011699624s","start":"2026-02-19T02:53:12.37756Z","end":"2026-02-19T02:53:13.389259Z","steps":["trace[1220007791] 'read index received' (duration: 705.141025ms)","trace[1220007791] 'applied index is now lower than readState.Index' (duration: 306.555999ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:53:13.389476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011889948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:53:13.389583Z","caller":"traceutil/trace.go:171","msg":"trace[2036031937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:878; }","duration":"1.012032541s","start":"2026-02-19T02:53:12.37752Z","end":"2026-02-19T02:53:13.389552Z","steps":["trace[2036031937] 'agreement among raft nodes before linearized reading' (duration: 1.011849057s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:53:13.389666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:53:12.377499Z","time spent":"1.012151033s","remote":"127.0.0.1:50856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:53:13.389746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.231566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-19T02:53:13.389749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.679933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-19T02:53:13.389743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.153772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T02:53:13.389808Z","caller":"traceutil/trace.go:171","msg":"trace[1420427749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:878; }","duration":"330.341878ms","start":"2026-02-19T02:53:13.059452Z","end":"2026-02-19T02:53:13.389794Z","steps":["trace[1420427749] 'agreement among raft nodes before linearized reading' (duration: 330.075573ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:53:13.389825Z","caller":"traceutil/trace.go:171","msg":"trace[1969150882] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:878; }","duration":"388.794546ms","start":"2026-02-19T02:53:13.001009Z","end":"2026-02-19T02:53:13.389804Z","steps":["trace[1969150882] 'agreement among raft nodes before linearized reading' (duration: 388.607841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:53:13.389855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:53:13.059432Z","time spent":"330.413229ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-19T02:53:13.389859Z","caller":"traceutil/trace.go:171","msg":"trace[113570828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:878; }","duration":"388.284065ms","start":"2026-02-19T02:53:13.001536Z","end":"2026-02-19T02:53:13.38982Z","steps":["trace[113570828] 'agreement among raft nodes before linearized reading' (duration: 387.944568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:53:13.389966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:53:13.001457Z","time spent":"388.490279ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T02:53:13.390123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.714997ms","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-02-19T02:53:13.390261Z","caller":"traceutil/trace.go:171","msg":"trace[1894041113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:878; }","duration":"672.045874ms","start":"2026-02-19T02:53:12.718185Z","end":"2026-02-19T02:53:13.390231Z","steps":["trace[1894041113] 'agreement among raft nodes before linearized reading' (duration: 671.298748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:53:13.390364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:53:12.718153Z","time spent":"672.194987ms","remote":"127.0.0.1:55632","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-02-19T02:53:13.389882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:53:13.000986Z","time spent":"388.883957ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T02:53:13.390173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.395693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-19T02:53:13.390626Z","caller":"traceutil/trace.go:171","msg":"trace[952192784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:878; }","duration":"446.879434ms","start":"2026-02-19T02:53:12.943732Z","end":"2026-02-19T02:53:13.390611Z","steps":["trace[952192784] 'agreement among raft nodes before linearized reading' (duration: 446.202509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:53:13.390901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:53:12.943714Z","time spent":"446.986216ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T02:53:15.198818Z","caller":"traceutil/trace.go:171","msg":"trace[514375020] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"128.370085ms","start":"2026-02-19T02:53:15.070428Z","end":"2026-02-19T02:53:15.198798Z","steps":["trace[514375020] 'process raft request' (duration: 128.247642ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:53:18.33001Z","caller":"traceutil/trace.go:171","msg":"trace[378160781] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"223.684378ms","start":"2026-02-19T02:53:18.106301Z","end":"2026-02-19T02:53:18.329985Z","steps":["trace[378160781] 'process raft request' (duration: 223.532593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:53:31.30947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.753465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-8trnv\" ","response":"range_response_count:1 size:4152"} {"level":"info","ts":"2026-02-19T02:53:31.309613Z","caller":"traceutil/trace.go:171","msg":"trace[285047316] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-8trnv; range_end:; response_count:1; response_revision:1032; }","duration":"183.9761ms","start":"2026-02-19T02:53:31.125603Z","end":"2026-02-19T02:53:31.309579Z","steps":["trace[285047316] 'range keys from in-memory index tree' (duration: 183.404667ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:53:36.43585Z","caller":"traceutil/trace.go:171","msg":"trace[784071406] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"172.949448ms","start":"2026-02-19T02:53:36.262869Z","end":"2026-02-19T02:53:36.435819Z","steps":["trace[784071406] 'process raft request' (duration: 158.081693ms)","trace[784071406] 'compare' (duration: 14.716662ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:53:36.436993Z","caller":"traceutil/trace.go:171","msg":"trace[1122145123] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"169.528353ms","start":"2026-02-19T02:53:36.267435Z","end":"2026-02-19T02:53:36.436963Z","steps":["trace[1122145123] 'process raft request' (duration: 169.332138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:53:38.358619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.218732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:53:38.358688Z","caller":"traceutil/trace.go:171","msg":"trace[641246792] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1150; }","duration":"107.347155ms","start":"2026-02-19T02:53:38.251324Z","end":"2026-02-19T02:53:38.358671Z","steps":["trace[641246792] 'count revisions from in-memory index tree' (duration: 107.128549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:53:39.736805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.486223ms","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-02-19T02:53:39.736879Z","caller":"traceutil/trace.go:171","msg":"trace[717685194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1153; }","duration":"137.611166ms","start":"2026-02-19T02:53:39.599248Z","end":"2026-02-19T02:53:39.736859Z","steps":["trace[717685194] 'range keys from in-memory index tree' (duration: 137.32283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:53:39.736958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.326481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T02:53:39.737002Z","caller":"traceutil/trace.go:171","msg":"trace[372182121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1153; }","duration":"143.406422ms","start":"2026-02-19T02:53:39.593584Z","end":"2026-02-19T02:53:39.736991Z","steps":["trace[372182121] 'range keys from in-memory index tree' (duration: 143.214519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:53:57.370508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.003017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:53:57.370658Z","caller":"traceutil/trace.go:171","msg":"trace[795684190] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"247.705139ms","start":"2026-02-19T02:53:57.122928Z","end":"2026-02-19T02:53:57.370633Z","steps":["trace[795684190] 'process raft request' (duration: 128.163382ms)","trace[795684190] 'compare' (duration: 118.799232ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:53:57.405597Z","caller":"traceutil/trace.go:171","msg":"trace[1084123986] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"254.570976ms","start":"2026-02-19T02:53:57.151007Z","end":"2026-02-19T02:53:57.405578Z","steps":["trace[1084123986] 'process raft request' (duration: 254.422232ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:53:57.407086Z","caller":"traceutil/trace.go:171","msg":"trace[1588226191] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"105.700713ms","start":"2026-02-19T02:53:57.301358Z","end":"2026-02-19T02:53:57.407059Z","steps":["trace[1588226191] 'process raft request' (duration: 105.488318ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:54:05.278335Z","caller":"traceutil/trace.go:171","msg":"trace[663101436] linearizableReadLoop","detail":"{readStateIndex:1633; appliedIndex:1632; }","duration":"328.607663ms","start":"2026-02-19T02:54:04.949705Z","end":"2026-02-19T02:54:05.278313Z","steps":["trace[663101436] 'read index received' (duration: 328.385589ms)","trace[663101436] 'applied index is now lower than readState.Index' (duration: 221.254µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:54:05.278505Z","caller":"traceutil/trace.go:171","msg":"trace[1962844957] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"932.42802ms","start":"2026-02-19T02:54:04.346048Z","end":"2026-02-19T02:54:05.278476Z","steps":["trace[1962844957] 'process raft request' (duration: 932.116153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:05.278557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.832018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:54:05.278606Z","caller":"traceutil/trace.go:171","msg":"trace[1259337874] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1567; }","duration":"328.903809ms","start":"2026-02-19T02:54:04.949691Z","end":"2026-02-19T02:54:05.278595Z","steps":["trace[1259337874] 'agreement among raft nodes before linearized reading' (duration: 328.798877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:05.278648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:04.949615Z","time spent":"329.022972ms","remote":"127.0.0.1:55864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-19T02:54:05.278765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:04.346026Z","time spent":"932.613964ms","remote":"127.0.0.1:55558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3671,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:54:05.279172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.019718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efbcc025-fd7f-4fd5-9935-103ee2d8b438\" ","response":"range_response_count:1 size:3686"} {"level":"info","ts":"2026-02-19T02:54:05.279239Z","caller":"traceutil/trace.go:171","msg":"trace[1254700693] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-efbcc025-fd7f-4fd5-9935-103ee2d8b438; range_end:; response_count:1; response_revision:1567; }","duration":"166.136651ms","start":"2026-02-19T02:54:05.113089Z","end":"2026-02-19T02:54:05.279225Z","steps":["trace[1254700693] 'agreement among raft nodes before linearized reading' (duration: 166.042439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:05.279724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.857963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5324"} {"level":"info","ts":"2026-02-19T02:54:05.279799Z","caller":"traceutil/trace.go:171","msg":"trace[946204078] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1567; }","duration":"263.946115ms","start":"2026-02-19T02:54:05.015818Z","end":"2026-02-19T02:54:05.279764Z","steps":["trace[946204078] 'agreement among raft nodes before linearized reading' (duration: 263.272521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:05.951663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.124675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:54:05.951757Z","caller":"traceutil/trace.go:171","msg":"trace[791699616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1567; }","duration":"575.263458ms","start":"2026-02-19T02:54:05.376471Z","end":"2026-02-19T02:54:05.951734Z","steps":["trace[791699616] 'range keys from in-memory index tree' (duration: 575.029424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:05.951806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:05.376451Z","time spent":"575.345511ms","remote":"127.0.0.1:50856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:54:05.952346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.514149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:54:05.952602Z","caller":"traceutil/trace.go:171","msg":"trace[28691817] linearizableReadLoop","detail":"{readStateIndex:1636; appliedIndex:1633; }","duration":"147.921763ms","start":"2026-02-19T02:54:05.804659Z","end":"2026-02-19T02:54:05.952581Z","steps":["trace[28691817] 'read index received' (duration: 122.318197ms)","trace[28691817] 'applied index is now lower than readState.Index' (duration: 25.602456ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:54:05.952595Z","caller":"traceutil/trace.go:171","msg":"trace[1901806834] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"536.359349ms","start":"2026-02-19T02:54:05.416201Z","end":"2026-02-19T02:54:05.952561Z","steps":["trace[1901806834] 'process raft request' (duration: 376.553793ms)","trace[1901806834] 'compare' (duration: 159.103281ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:54:05.952646Z","caller":"traceutil/trace.go:171","msg":"trace[803117447] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"517.573288ms","start":"2026-02-19T02:54:05.435052Z","end":"2026-02-19T02:54:05.952625Z","steps":["trace[803117447] 'process raft request' (duration: 517.491306ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:54:05.952716Z","caller":"traceutil/trace.go:171","msg":"trace[821754555] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"524.396075ms","start":"2026-02-19T02:54:05.428205Z","end":"2026-02-19T02:54:05.952601Z","steps":["trace[821754555] 'process raft request' (duration: 524.258512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:05.952911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:05.435021Z","time spent":"517.753902ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:54:05.952748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.091536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:54:05.953016Z","caller":"traceutil/trace.go:171","msg":"trace[1563669495] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1570; }","duration":"148.398173ms","start":"2026-02-19T02:54:05.804605Z","end":"2026-02-19T02:54:05.953003Z","steps":["trace[1563669495] 'agreement among raft nodes before linearized reading' (duration: 148.075926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:05.953082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:05.428186Z","time spent":"524.658781ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:54:05.952755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:05.416173Z","time spent":"536.500132ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:54:24.371556Z","caller":"traceutil/trace.go:171","msg":"trace[1288397632] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"359.975411ms","start":"2026-02-19T02:54:24.011554Z","end":"2026-02-19T02:54:24.37153Z","steps":["trace[1288397632] 'process raft request' (duration: 359.814748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:24.371776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:24.011531Z","time spent":"360.168845ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":553,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:54:24.372767Z","caller":"traceutil/trace.go:171","msg":"trace[1465120504] linearizableReadLoop","detail":"{readStateIndex:1780; appliedIndex:1777; }","duration":"349.899676ms","start":"2026-02-19T02:54:24.022842Z","end":"2026-02-19T02:54:24.372742Z","steps":["trace[1465120504] 'read index received' (duration: 348.543187ms)","trace[1465120504] 'applied index is now lower than readState.Index' (duration: 1.355499ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:54:24.372797Z","caller":"traceutil/trace.go:171","msg":"trace[1792471024] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"358.625222ms","start":"2026-02-19T02:54:24.014146Z","end":"2026-02-19T02:54:24.372771Z","steps":["trace[1792471024] 'process raft request' (duration: 358.421018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:24.372952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:24.014129Z","time spent":"358.741835ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:54:24.373019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.514057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-19T02:54:24.373015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.143061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-19T02:54:24.373076Z","caller":"traceutil/trace.go:171","msg":"trace[1285967816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1708; }","duration":"302.602609ms","start":"2026-02-19T02:54:24.070462Z","end":"2026-02-19T02:54:24.373065Z","steps":["trace[1285967816] 'agreement among raft nodes before linearized reading' (duration: 302.438926ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:54:24.373089Z","caller":"traceutil/trace.go:171","msg":"trace[576876325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1708; }","duration":"350.266734ms","start":"2026-02-19T02:54:24.022807Z","end":"2026-02-19T02:54:24.373073Z","steps":["trace[576876325] 'agreement among raft nodes before linearized reading' (duration: 350.05366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:24.373103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.959111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-19T02:54:24.373124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:24.022789Z","time spent":"350.329465ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T02:54:24.373134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:24.070448Z","time spent":"302.66231ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-19T02:54:24.373153Z","caller":"traceutil/trace.go:171","msg":"trace[446171794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1708; }","duration":"299.040803ms","start":"2026-02-19T02:54:24.074099Z","end":"2026-02-19T02:54:24.37314Z","steps":["trace[446171794] 'agreement among raft nodes before linearized reading' (duration: 298.875649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:24.373197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.895148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-19T02:54:24.373279Z","caller":"traceutil/trace.go:171","msg":"trace[266333921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1708; }","duration":"298.02487ms","start":"2026-02-19T02:54:24.075236Z","end":"2026-02-19T02:54:24.373261Z","steps":["trace[266333921] 'agreement among raft nodes before linearized reading' (duration: 297.831886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:24.373316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.679039ms","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-02-19T02:54:24.373354Z","caller":"traceutil/trace.go:171","msg":"trace[1662608404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1708; }","duration":"301.752521ms","start":"2026-02-19T02:54:24.07159Z","end":"2026-02-19T02:54:24.373343Z","steps":["trace[1662608404] 'agreement among raft nodes before linearized reading' (duration: 301.618878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:24.373406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:24.071566Z","time spent":"301.826182ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-19T02:54:24.372851Z","caller":"traceutil/trace.go:171","msg":"trace[775187697] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"355.203149ms","start":"2026-02-19T02:54:24.017629Z","end":"2026-02-19T02:54:24.372832Z","steps":["trace[775187697] 'process raft request' (duration: 355.073687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:24.373556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:24.017594Z","time spent":"355.924035ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:54:26.554374Z","caller":"traceutil/trace.go:171","msg":"trace[1668835725] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"175.265184ms","start":"2026-02-19T02:54:26.379086Z","end":"2026-02-19T02:54:26.554351Z","steps":["trace[1668835725] 'process raft request' (duration: 175.124431ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:54:26.592616Z","caller":"traceutil/trace.go:171","msg":"trace[1734091772] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"213.175712ms","start":"2026-02-19T02:54:26.379417Z","end":"2026-02-19T02:54:26.592592Z","steps":["trace[1734091772] 'process raft request' (duration: 212.934447ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:54:26.592775Z","caller":"traceutil/trace.go:171","msg":"trace[1633912526] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"212.445137ms","start":"2026-02-19T02:54:26.380307Z","end":"2026-02-19T02:54:26.592753Z","steps":["trace[1633912526] 'process raft request' (duration: 212.172901ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:54:26.592788Z","caller":"traceutil/trace.go:171","msg":"trace[1698154024] linearizableReadLoop","detail":"{readStateIndex:1788; appliedIndex:1785; }","duration":"209.74428ms","start":"2026-02-19T02:54:26.383022Z","end":"2026-02-19T02:54:26.592766Z","steps":["trace[1698154024] 'read index received' (duration: 171.175928ms)","trace[1698154024] 'applied index is now lower than readState.Index' (duration: 38.566302ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:54:26.593011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.985915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-19T02:54:26.59304Z","caller":"traceutil/trace.go:171","msg":"trace[385447727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1716; }","duration":"210.052727ms","start":"2026-02-19T02:54:26.382981Z","end":"2026-02-19T02:54:26.593034Z","steps":["trace[385447727] 'agreement among raft nodes before linearized reading' (duration: 209.952055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:26.593141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.205126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-19T02:54:26.593212Z","caller":"traceutil/trace.go:171","msg":"trace[1542629283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1716; }","duration":"207.305758ms","start":"2026-02-19T02:54:26.385894Z","end":"2026-02-19T02:54:26.593199Z","steps":["trace[1542629283] 'agreement among raft nodes before linearized reading' (duration: 207.108604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:26.593261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.928098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5324"} {"level":"warn","ts":"2026-02-19T02:54:26.593275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.142183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-19T02:54:26.593298Z","caller":"traceutil/trace.go:171","msg":"trace[2051786084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1716; }","duration":"206.224884ms","start":"2026-02-19T02:54:26.387067Z","end":"2026-02-19T02:54:26.593291Z","steps":["trace[2051786084] 'agreement among raft nodes before linearized reading' (duration: 206.168113ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:54:26.593305Z","caller":"traceutil/trace.go:171","msg":"trace[1789349736] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1716; }","duration":"160.97805ms","start":"2026-02-19T02:54:26.432305Z","end":"2026-02-19T02:54:26.593283Z","steps":["trace[1789349736] 'agreement among raft nodes before linearized reading' (duration: 160.900928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:26.593378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.281315ms","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-02-19T02:54:26.593171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.748024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-19T02:54:26.593476Z","caller":"traceutil/trace.go:171","msg":"trace[1028124377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1716; }","duration":"206.05105ms","start":"2026-02-19T02:54:26.387382Z","end":"2026-02-19T02:54:26.593433Z","steps":["trace[1028124377] 'agreement among raft nodes before linearized reading' (duration: 205.676542ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:54:26.5934Z","caller":"traceutil/trace.go:171","msg":"trace[580484655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1716; }","duration":"206.342806ms","start":"2026-02-19T02:54:26.38705Z","end":"2026-02-19T02:54:26.593393Z","steps":["trace[580484655] 'agreement among raft nodes before linearized reading' (duration: 206.295015ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:54:30.715279Z","caller":"traceutil/trace.go:171","msg":"trace[77633084] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"110.411164ms","start":"2026-02-19T02:54:30.604843Z","end":"2026-02-19T02:54:30.715254Z","steps":["trace[77633084] 'process raft request' (duration: 71.159107ms)","trace[77633084] 'compare' (duration: 39.069633ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:55:02.878076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674848113899612,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:55:03.047444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.021569ms","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-02-19T02:55:03.047584Z","caller":"traceutil/trace.go:171","msg":"trace[679995434] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1895; }","duration":"147.157431ms","start":"2026-02-19T02:55:02.900394Z","end":"2026-02-19T02:55:03.047552Z","steps":["trace[679995434] 'range keys from in-memory index tree' (duration: 147.005418ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:55:03.047611Z","caller":"traceutil/trace.go:171","msg":"trace[684070323] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"762.624065ms","start":"2026-02-19T02:55:02.28496Z","end":"2026-02-19T02:55:03.047584Z","steps":["trace[684070323] 'process raft request' (duration: 750.305382ms)","trace[684070323] 'compare' (duration: 11.904065ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:55:03.047742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.284946Z","time spent":"762.723827ms","remote":"127.0.0.1:55456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:55:03.917812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.604824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:55:03.918046Z","caller":"traceutil/trace.go:171","msg":"trace[1072347545] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1975; }","duration":"1.540889503s","start":"2026-02-19T02:55:02.377139Z","end":"2026-02-19T02:55:03.918028Z","steps":["trace[1072347545] 'read index received' (duration: 658.272855ms)","trace[1072347545] 'applied index is now lower than readState.Index' (duration: 882.615598ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:55:03.918071Z","caller":"traceutil/trace.go:171","msg":"trace[1056738882] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"1.205089733s","start":"2026-02-19T02:55:02.712938Z","end":"2026-02-19T02:55:03.918028Z","steps":["trace[1056738882] 'process raft request' (duration: 691.185053ms)","trace[1056738882] 'compare' (duration: 513.198665ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:55:03.918156Z","caller":"traceutil/trace.go:171","msg":"trace[1145202596] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"1.095118947s","start":"2026-02-19T02:55:02.822983Z","end":"2026-02-19T02:55:03.918102Z","steps":["trace[1145202596] 'process raft request' (duration: 1.094928943s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:03.918268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280947906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12187"} {"level":"warn","ts":"2026-02-19T02:55:03.918271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541120737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:55:03.918326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.822957Z","time spent":"1.095282701s","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:55:03.918295Z","caller":"traceutil/trace.go:171","msg":"trace[1754581742] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"1.094501704s","start":"2026-02-19T02:55:02.823757Z","end":"2026-02-19T02:55:03.918259Z","steps":["trace[1754581742] 'process raft request' (duration: 1.094230869s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:55:03.918389Z","caller":"traceutil/trace.go:171","msg":"trace[1017772761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1899; }","duration":"1.54126194s","start":"2026-02-19T02:55:02.377083Z","end":"2026-02-19T02:55:03.918345Z","steps":["trace[1017772761] 'agreement among raft nodes before linearized reading' (duration: 1.541053426s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:03.918409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226484724s","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":"warn","ts":"2026-02-19T02:55:03.918436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.377061Z","time spent":"1.541366163s","remote":"127.0.0.1:50856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T02:55:03.918443Z","caller":"traceutil/trace.go:171","msg":"trace[230606158] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1899; }","duration":"1.226545925s","start":"2026-02-19T02:55:02.691888Z","end":"2026-02-19T02:55:03.918434Z","steps":["trace[230606158] 'agreement among raft nodes before linearized reading' (duration: 1.226449063s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:03.918439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.382351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:55:03.918492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.691872Z","time spent":"1.226605617s","remote":"127.0.0.1:55710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":20,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-19T02:55:03.918491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035134201s","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-02-19T02:55:03.918529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036439158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-19T02:55:03.918517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.82374Z","time spent":"1.094685519s","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:55:03.918553Z","caller":"traceutil/trace.go:171","msg":"trace[26070477] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1899; }","duration":"154.540835ms","start":"2026-02-19T02:55:03.763983Z","end":"2026-02-19T02:55:03.918524Z","steps":["trace[26070477] 'agreement among raft nodes before linearized reading' (duration: 154.32654ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:55:03.918565Z","caller":"traceutil/trace.go:171","msg":"trace[1745024237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1899; }","duration":"1.035228882s","start":"2026-02-19T02:55:02.883316Z","end":"2026-02-19T02:55:03.918545Z","steps":["trace[1745024237] 'agreement among raft nodes before linearized reading' (duration: 1.034957897s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:03.918557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.780144ms","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":"warn","ts":"2026-02-19T02:55:03.918576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04144425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-19T02:55:03.91861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.883306Z","time spent":"1.035295224s","remote":"127.0.0.1:55632","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":"info","ts":"2026-02-19T02:55:03.918622Z","caller":"traceutil/trace.go:171","msg":"trace[1776922419] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1899; }","duration":"707.896656ms","start":"2026-02-19T02:55:03.210707Z","end":"2026-02-19T02:55:03.918604Z","steps":["trace[1776922419] 'agreement among raft nodes before linearized reading' (duration: 707.771324ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:55:03.918639Z","caller":"traceutil/trace.go:171","msg":"trace[93815423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1899; }","duration":"1.041546573s","start":"2026-02-19T02:55:02.877076Z","end":"2026-02-19T02:55:03.918623Z","steps":["trace[93815423] 'agreement among raft nodes before linearized reading' (duration: 1.041223196s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:03.918675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.877059Z","time spent":"1.041608624s","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-19T02:55:03.918306Z","caller":"traceutil/trace.go:171","msg":"trace[1692906187] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1899; }","duration":"1.281024478s","start":"2026-02-19T02:55:02.637271Z","end":"2026-02-19T02:55:03.918296Z","steps":["trace[1692906187] 'agreement among raft nodes before linearized reading' (duration: 1.280860485s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:03.918778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037588782s","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-02-19T02:55:03.918781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051910866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-19T02:55:03.918799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.48917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5324"} {"level":"info","ts":"2026-02-19T02:55:03.918825Z","caller":"traceutil/trace.go:171","msg":"trace[315682642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1899; }","duration":"1.037653223s","start":"2026-02-19T02:55:02.88116Z","end":"2026-02-19T02:55:03.918813Z","steps":["trace[315682642] 'agreement among raft nodes before linearized reading' (duration: 1.03753597s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:03.918674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:03.210684Z","time spent":"707.979239ms","remote":"127.0.0.1:57530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-02-19T02:55:03.918844Z","caller":"traceutil/trace.go:171","msg":"trace[569967402] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1899; }","duration":"972.551781ms","start":"2026-02-19T02:55:02.946277Z","end":"2026-02-19T02:55:03.918829Z","steps":["trace[569967402] 'agreement among raft nodes before linearized reading' (duration: 972.386158ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:55:03.918826Z","caller":"traceutil/trace.go:171","msg":"trace[991785116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1899; }","duration":"1.052010429s","start":"2026-02-19T02:55:02.866806Z","end":"2026-02-19T02:55:03.918817Z","steps":["trace[991785116] 'agreement among raft nodes before linearized reading' (duration: 1.051521599s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:03.918892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.712916Z","time spent":"1.205222606s","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:55:03.918939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.866785Z","time spent":"1.052136081s","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T02:55:03.918779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.637251Z","time spent":"1.281519088s","remote":"127.0.0.1:55558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12210,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-02-19T02:55:03.918934Z","caller":"traceutil/trace.go:171","msg":"trace[1405966742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1899; }","duration":"1.036522129s","start":"2026-02-19T02:55:02.882034Z","end":"2026-02-19T02:55:03.918556Z","steps":["trace[1405966742] 'agreement among raft nodes before linearized reading' (duration: 1.036345046s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:03.918862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.881153Z","time spent":"1.037701944s","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T02:55:03.919073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.882028Z","time spent":"1.03702055s","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T02:55:03.918879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:02.946266Z","time spent":"972.605423ms","remote":"127.0.0.1:57536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5347,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-19T02:55:04.495007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.628816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:55:04.495226Z","caller":"traceutil/trace.go:171","msg":"trace[1815355427] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1979; }","duration":"569.486084ms","start":"2026-02-19T02:55:03.925727Z","end":"2026-02-19T02:55:04.495213Z","steps":["trace[1815355427] 'read index received' (duration: 380.610813ms)","trace[1815355427] 'applied index is now lower than readState.Index' (duration: 188.874461ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:55:04.495295Z","caller":"traceutil/trace.go:171","msg":"trace[1540585670] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"572.292863ms","start":"2026-02-19T02:55:03.922996Z","end":"2026-02-19T02:55:04.495289Z","steps":["trace[1540585670] 'process raft request' (duration: 572.088499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:04.495332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:03.922972Z","time spent":"572.342004ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:55:04.495421Z","caller":"traceutil/trace.go:171","msg":"trace[271537762] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"571.414675ms","start":"2026-02-19T02:55:03.92393Z","end":"2026-02-19T02:55:04.495344Z","steps":["trace[271537762] 'process raft request' (duration: 571.258292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:04.495534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.806361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:55:04.49555Z","caller":"traceutil/trace.go:171","msg":"trace[2098984466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1904; }","duration":"569.842852ms","start":"2026-02-19T02:55:03.925701Z","end":"2026-02-19T02:55:04.495544Z","steps":["trace[2098984466] 'agreement among raft nodes before linearized reading' (duration: 569.810042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:04.495566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:03.925694Z","time spent":"569.869333ms","remote":"127.0.0.1:50856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:55:04.495578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:03.923916Z","time spent":"571.587468ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:55:04.495633Z","caller":"traceutil/trace.go:171","msg":"trace[1528692361] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"572.356394ms","start":"2026-02-19T02:55:03.923255Z","end":"2026-02-19T02:55:04.495611Z","steps":["trace[1528692361] 'process raft request' (duration: 571.886954ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:55:04.49565Z","caller":"traceutil/trace.go:171","msg":"trace[840050543] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"571.890445ms","start":"2026-02-19T02:55:03.923731Z","end":"2026-02-19T02:55:04.495622Z","steps":["trace[840050543] 'process raft request' (duration: 571.431065ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:55:04.495657Z","caller":"traceutil/trace.go:171","msg":"trace[2118713277] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"572.62321ms","start":"2026-02-19T02:55:03.922994Z","end":"2026-02-19T02:55:04.495617Z","steps":["trace[2118713277] 'process raft request' (duration: 383.292429ms)","trace[2118713277] 'compare' (duration: 188.29908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:55:04.495695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.873813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:55:04.495739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:03.923241Z","time spent":"572.440636ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:55:04.495794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:03.923707Z","time spent":"572.004357ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:55:04.495807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:03.922972Z","time spent":"572.758392ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:55:04.495771Z","caller":"traceutil/trace.go:171","msg":"trace[1210034170] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1904; }","duration":"569.971514ms","start":"2026-02-19T02:55:03.925777Z","end":"2026-02-19T02:55:04.495749Z","steps":["trace[1210034170] 'agreement among raft nodes before linearized reading' (duration: 569.844992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:55:04.495885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:55:03.925769Z","time spent":"570.102368ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-02-19T02:55:04.496323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.212014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-19T02:55:04.496462Z","caller":"traceutil/trace.go:171","msg":"trace[1547192193] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1904; }","duration":"297.652874ms","start":"2026-02-19T02:55:04.198781Z","end":"2026-02-19T02:55:04.496434Z","steps":["trace[1547192193] 'agreement among raft nodes before linearized reading' (duration: 296.848767ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:56:04.718601Z","caller":"traceutil/trace.go:171","msg":"trace[564153895] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"200.060789ms","start":"2026-02-19T02:56:04.518502Z","end":"2026-02-19T02:56:04.718562Z","steps":["trace[564153895] 'process raft request' (duration: 149.857355ms)","trace[564153895] 'compare' (duration: 49.908228ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:56:04.719627Z","caller":"traceutil/trace.go:171","msg":"trace[599101430] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"136.652289ms","start":"2026-02-19T02:56:04.58296Z","end":"2026-02-19T02:56:04.719612Z","steps":["trace[599101430] 'process raft request' (duration: 136.493976ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:56:15.002398Z","caller":"traceutil/trace.go:171","msg":"trace[393072973] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"129.811742ms","start":"2026-02-19T02:56:14.872551Z","end":"2026-02-19T02:56:15.002363Z","steps":["trace[393072973] 'process raft request' (duration: 129.624938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:56:48.856873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.695611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:56:48.857057Z","caller":"traceutil/trace.go:171","msg":"trace[197639929] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"404.970338ms","start":"2026-02-19T02:56:48.45206Z","end":"2026-02-19T02:56:48.85703Z","steps":["trace[197639929] 'process raft request' (duration: 264.989701ms)","trace[197639929] 'compare' (duration: 138.966867ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:56:48.857255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:56:48.452037Z","time spent":"405.123931ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:56:49.384597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.370535ms","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-02-19T02:56:49.384694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.678836ms","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-02-19T02:56:49.384775Z","caller":"traceutil/trace.go:171","msg":"trace[1639096458] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2597; }","duration":"273.801178ms","start":"2026-02-19T02:56:49.110956Z","end":"2026-02-19T02:56:49.384757Z","steps":["trace[1639096458] 'range keys from in-memory index tree' (duration: 273.470462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:56:49.384821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.287045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T02:56:49.384904Z","caller":"traceutil/trace.go:171","msg":"trace[1917427014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2597; }","duration":"155.402668ms","start":"2026-02-19T02:56:49.229483Z","end":"2026-02-19T02:56:49.384886Z","steps":["trace[1917427014] 'range keys from in-memory index tree' (duration: 155.118192ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:56:49.384703Z","caller":"traceutil/trace.go:171","msg":"trace[1894754843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2597; }","duration":"197.496178ms","start":"2026-02-19T02:56:49.187178Z","end":"2026-02-19T02:56:49.384674Z","steps":["trace[1894754843] 'range keys from in-memory index tree' (duration: 196.997727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:56:49.384918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.140684ms","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-02-19T02:56:49.385042Z","caller":"traceutil/trace.go:171","msg":"trace[168709520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2597; }","duration":"160.330208ms","start":"2026-02-19T02:56:49.224682Z","end":"2026-02-19T02:56:49.385012Z","steps":["trace[168709520] 'range keys from in-memory index tree' (duration: 159.845028ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:56:49.895465Z","caller":"traceutil/trace.go:171","msg":"trace[1075562675] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"506.420166ms","start":"2026-02-19T02:56:49.38901Z","end":"2026-02-19T02:56:49.89543Z","steps":["trace[1075562675] 'process raft request' (duration: 494.881343ms)","trace[1075562675] 'compare' (duration: 10.765407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:56:49.895597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:56:49.388995Z","time spent":"506.537558ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:56:49.895543Z","caller":"traceutil/trace.go:171","msg":"trace[503191247] linearizableReadLoop","detail":"{readStateIndex:2710; appliedIndex:2706; }","duration":"505.352585ms","start":"2026-02-19T02:56:49.390151Z","end":"2026-02-19T02:56:49.895504Z","steps":["trace[503191247] 'read index received' (duration: 17.688358ms)","trace[503191247] 'applied index is now lower than readState.Index' (duration: 487.662827ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:56:49.89563Z","caller":"traceutil/trace.go:171","msg":"trace[1856252686] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"505.510008ms","start":"2026-02-19T02:56:49.390089Z","end":"2026-02-19T02:56:49.895599Z","steps":["trace[1856252686] 'process raft request' (duration: 505.321605ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:56:49.895616Z","caller":"traceutil/trace.go:171","msg":"trace[1558817275] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"505.910846ms","start":"2026-02-19T02:56:49.389669Z","end":"2026-02-19T02:56:49.89558Z","steps":["trace[1558817275] 'process raft request' (duration: 505.60745ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:56:49.89564Z","caller":"traceutil/trace.go:171","msg":"trace[801322529] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"504.517208ms","start":"2026-02-19T02:56:49.391094Z","end":"2026-02-19T02:56:49.895612Z","steps":["trace[801322529] 'process raft request' (duration: 504.357164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:56:49.895812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:56:49.390076Z","time spent":"505.649161ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:56:49.895839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:56:49.389658Z","time spent":"506.085499ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:56:49.895942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:56:49.391086Z","time spent":"504.730012ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:56:49.896092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.897076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1313"} {"level":"info","ts":"2026-02-19T02:56:49.89622Z","caller":"traceutil/trace.go:171","msg":"trace[2075972257] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2602; }","duration":"506.053039ms","start":"2026-02-19T02:56:49.390141Z","end":"2026-02-19T02:56:49.896194Z","steps":["trace[2075972257] 'agreement among raft nodes before linearized reading' (duration: 505.494388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:56:49.896293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:56:49.39012Z","time spent":"506.160751ms","remote":"127.0.0.1:55570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1336,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-02-19T02:56:56.080852Z","caller":"traceutil/trace.go:171","msg":"trace[1260304639] transaction","detail":"{read_only:false; response_revision:2632; number_of_response:1; }","duration":"157.515242ms","start":"2026-02-19T02:56:55.92331Z","end":"2026-02-19T02:56:56.080825Z","steps":["trace[1260304639] 'process raft request' (duration: 136.437296ms)","trace[1260304639] 'compare' (duration: 20.918183ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:56:56.081112Z","caller":"traceutil/trace.go:171","msg":"trace[1240028967] transaction","detail":"{read_only:false; response_revision:2633; number_of_response:1; }","duration":"157.785387ms","start":"2026-02-19T02:56:55.923317Z","end":"2026-02-19T02:56:56.081102Z","steps":["trace[1240028967] 'process raft request' (duration: 157.469391ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:56:56.081235Z","caller":"traceutil/trace.go:171","msg":"trace[1400304110] transaction","detail":"{read_only:false; response_revision:2634; number_of_response:1; }","duration":"157.095313ms","start":"2026-02-19T02:56:55.924125Z","end":"2026-02-19T02:56:56.08122Z","steps":["trace[1400304110] 'process raft request' (duration: 156.94384ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:42.85184Z","caller":"traceutil/trace.go:171","msg":"trace[423759581] transaction","detail":"{read_only:false; response_revision:3307; number_of_response:1; }","duration":"183.877032ms","start":"2026-02-19T02:58:42.667932Z","end":"2026-02-19T02:58:42.851809Z","steps":["trace[423759581] 'process raft request' (duration: 178.212438ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:58:42.851843Z","caller":"traceutil/trace.go:171","msg":"trace[925283734] transaction","detail":"{read_only:false; response_revision:3308; number_of_response:1; }","duration":"183.727829ms","start":"2026-02-19T02:58:42.668082Z","end":"2026-02-19T02:58:42.851809Z","steps":["trace[925283734] 'process raft request' (duration: 183.607796ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:59:21.15422Z","caller":"traceutil/trace.go:171","msg":"trace[1993729664] transaction","detail":"{read_only:false; response_revision:3525; number_of_response:1; }","duration":"166.961539ms","start":"2026-02-19T02:59:20.987213Z","end":"2026-02-19T02:59:21.154175Z","steps":["trace[1993729664] 'process raft request' (duration: 161.196034ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:59:21.155327Z","caller":"traceutil/trace.go:171","msg":"trace[1756768321] transaction","detail":"{read_only:false; response_revision:3526; number_of_response:1; }","duration":"165.501241ms","start":"2026-02-19T02:59:20.989806Z","end":"2026-02-19T02:59:21.155307Z","steps":["trace[1756768321] 'process raft request' (duration: 165.321967ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:59:21.155305Z","caller":"traceutil/trace.go:171","msg":"trace[106555896] linearizableReadLoop","detail":"{readStateIndex:3671; appliedIndex:3669; }","duration":"118.722982ms","start":"2026-02-19T02:59:21.036556Z","end":"2026-02-19T02:59:21.155279Z","steps":["trace[106555896] 'read index received' (duration: 111.880174ms)","trace[106555896] 'applied index is now lower than readState.Index' (duration: 6.841258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:59:21.155526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.950097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-19T02:59:21.155577Z","caller":"traceutil/trace.go:171","msg":"trace[1420408414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3526; }","duration":"119.060379ms","start":"2026-02-19T02:59:21.036506Z","end":"2026-02-19T02:59:21.155566Z","steps":["trace[1420408414] 'agreement among raft nodes before linearized reading' (duration: 118.864815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:59:21.155703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.305084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T02:59:21.155802Z","caller":"traceutil/trace.go:171","msg":"trace[1742728130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3526; }","duration":"118.426716ms","start":"2026-02-19T02:59:21.037347Z","end":"2026-02-19T02:59:21.155774Z","steps":["trace[1742728130] 'agreement among raft nodes before linearized reading' (duration: 118.188561ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:00:33.785715Z","caller":"traceutil/trace.go:171","msg":"trace[2059080725] transaction","detail":"{read_only:false; response_revision:4432; number_of_response:1; }","duration":"105.957856ms","start":"2026-02-19T03:00:33.679735Z","end":"2026-02-19T03:00:33.785692Z","steps":["trace[2059080725] 'process raft request' (duration: 105.817823ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:00:44.312264Z","caller":"traceutil/trace.go:171","msg":"trace[867856107] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"110.352232ms","start":"2026-02-19T03:00:44.201882Z","end":"2026-02-19T03:00:44.312234Z","steps":["trace[867856107] 'process raft request' (duration: 110.095137ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:00:44.332919Z","caller":"traceutil/trace.go:171","msg":"trace[1409415288] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"130.456671ms","start":"2026-02-19T03:00:44.202425Z","end":"2026-02-19T03:00:44.332881Z","steps":["trace[1409415288] 'process raft request' (duration: 129.93526ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:00:44.332935Z","caller":"traceutil/trace.go:171","msg":"trace[1689605625] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"130.4154ms","start":"2026-02-19T03:00:44.202488Z","end":"2026-02-19T03:00:44.332903Z","steps":["trace[1689605625] 'process raft request' (duration: 130.175265ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:00:44.515969Z","caller":"traceutil/trace.go:171","msg":"trace[150671289] linearizableReadLoop","detail":"{readStateIndex:4920; appliedIndex:4919; }","duration":"174.643954ms","start":"2026-02-19T03:00:44.341283Z","end":"2026-02-19T03:00:44.515926Z","steps":["trace[150671289] 'read index received' (duration: 164.980053ms)","trace[150671289] 'applied index is now lower than readState.Index' (duration: 9.660421ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:00:44.516015Z","caller":"traceutil/trace.go:171","msg":"trace[1858455236] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"180.555891ms","start":"2026-02-19T03:00:44.335419Z","end":"2026-02-19T03:00:44.515975Z","steps":["trace[1858455236] 'process raft request' (duration: 170.839649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:00:44.516371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.076292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:61832"} {"level":"info","ts":"2026-02-19T03:00:44.51645Z","caller":"traceutil/trace.go:171","msg":"trace[1191936065] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4729; }","duration":"175.199764ms","start":"2026-02-19T03:00:44.341233Z","end":"2026-02-19T03:00:44.516433Z","steps":["trace[1191936065] 'agreement among raft nodes before linearized reading' (duration: 174.824527ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:00:44.517262Z","caller":"traceutil/trace.go:171","msg":"trace[1690845188] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"169.215067ms","start":"2026-02-19T03:00:44.348029Z","end":"2026-02-19T03:00:44.517244Z","steps":["trace[1690845188] 'process raft request' (duration: 168.951772ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:00:46.489969Z","caller":"traceutil/trace.go:171","msg":"trace[669459843] linearizableReadLoop","detail":"{readStateIndex:4965; appliedIndex:4964; }","duration":"198.999116ms","start":"2026-02-19T03:00:46.290945Z","end":"2026-02-19T03:00:46.489944Z","steps":["trace[669459843] 'read index received' (duration: 198.661649ms)","trace[669459843] 'applied index is now lower than readState.Index' (duration: 335.947µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:00:46.490043Z","caller":"traceutil/trace.go:171","msg":"trace[111886846] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"316.043531ms","start":"2026-02-19T03:00:46.173947Z","end":"2026-02-19T03:00:46.489991Z","steps":["trace[111886846] 'process raft request' (duration: 315.798796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:00:46.490231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.260961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-79767c77db-nd54n\" ","response":"range_response_count:1 size:7538"} {"level":"warn","ts":"2026-02-19T03:00:46.490234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:00:46.17392Z","time spent":"316.211815ms","remote":"127.0.0.1:55446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":128527,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:00:46.49028Z","caller":"traceutil/trace.go:171","msg":"trace[133460557] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-79767c77db-nd54n; range_end:; response_count:1; response_revision:4773; }","duration":"199.354512ms","start":"2026-02-19T03:00:46.290911Z","end":"2026-02-19T03:00:46.490266Z","steps":["trace[133460557] 'agreement among raft nodes before linearized reading' (duration: 199.168219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:00:46.490271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.088277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:00:46.490349Z","caller":"traceutil/trace.go:171","msg":"trace[2063865452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4773; }","duration":"112.22033ms","start":"2026-02-19T03:00:46.378109Z","end":"2026-02-19T03:00:46.490329Z","steps":["trace[2063865452] 'agreement among raft nodes before linearized reading' (duration: 112.066147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:00:46.490343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.363263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:00:46.490437Z","caller":"traceutil/trace.go:171","msg":"trace[684505340] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:4773; }","duration":"156.520426ms","start":"2026-02-19T03:00:46.333897Z","end":"2026-02-19T03:00:46.490418Z","steps":["trace[684505340] 'agreement among raft nodes before linearized reading' (duration: 156.336803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:00:46.490823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.844173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-hstzv.1895869e9ed3bee2\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-02-19T03:00:46.490895Z","caller":"traceutil/trace.go:171","msg":"trace[830668564] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-hstzv.1895869e9ed3bee2; range_end:; response_count:1; response_revision:4773; }","duration":"199.447104ms","start":"2026-02-19T03:00:46.291429Z","end":"2026-02-19T03:00:46.490876Z","steps":["trace[830668564] 'agreement among raft nodes before linearized reading' (duration: 198.69808ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:00:49.768921Z","caller":"traceutil/trace.go:171","msg":"trace[1289717236] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"103.16365ms","start":"2026-02-19T03:00:49.665714Z","end":"2026-02-19T03:00:49.768877Z","steps":["trace[1289717236] 'process raft request' (duration: 100.531058ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:00:50.417228Z","caller":"traceutil/trace.go:171","msg":"trace[167183074] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"106.391224ms","start":"2026-02-19T03:00:50.310812Z","end":"2026-02-19T03:00:50.417203Z","steps":["trace[167183074] 'process raft request' (duration: 106.18525ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:00:55.288822Z","caller":"traceutil/trace.go:171","msg":"trace[1050721854] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"103.183221ms","start":"2026-02-19T03:00:55.185611Z","end":"2026-02-19T03:00:55.288795Z","steps":["trace[1050721854] 'process raft request' (duration: 102.893394ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:00:55.488133Z","caller":"traceutil/trace.go:171","msg":"trace[546213418] linearizableReadLoop","detail":"{readStateIndex:5092; appliedIndex:5091; }","duration":"103.806864ms","start":"2026-02-19T03:00:55.384292Z","end":"2026-02-19T03:00:55.488098Z","steps":["trace[546213418] 'read index received' (duration: 103.453187ms)","trace[546213418] 'applied index is now lower than readState.Index' (duration: 352.047µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:00:55.488239Z","caller":"traceutil/trace.go:171","msg":"trace[263466834] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"116.015245ms","start":"2026-02-19T03:00:55.37217Z","end":"2026-02-19T03:00:55.488186Z","steps":["trace[263466834] 'process raft request' (duration: 115.679579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:00:55.488507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.198281ms","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-02-19T03:00:55.488609Z","caller":"traceutil/trace.go:171","msg":"trace[1214303773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4898; }","duration":"104.373314ms","start":"2026-02-19T03:00:55.384214Z","end":"2026-02-19T03:00:55.488587Z","steps":["trace[1214303773] 'agreement among raft nodes before linearized reading' (duration: 104.065808ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:11.863658Z","caller":"traceutil/trace.go:171","msg":"trace[189580970] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"125.879569ms","start":"2026-02-19T03:01:11.737749Z","end":"2026-02-19T03:01:11.863628Z","steps":["trace[189580970] 'process raft request' (duration: 125.711556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:26.045748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.284676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-02-19T03:01:26.045827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.007072ms","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-02-19T03:01:26.045875Z","caller":"traceutil/trace.go:171","msg":"trace[1590971168] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5221; }","duration":"320.462949ms","start":"2026-02-19T03:01:25.725385Z","end":"2026-02-19T03:01:26.045848Z","steps":["trace[1590971168] 'range keys from in-memory index tree' (duration: 320.077052ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:26.0459Z","caller":"traceutil/trace.go:171","msg":"trace[54561570] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5221; }","duration":"544.172675ms","start":"2026-02-19T03:01:25.501709Z","end":"2026-02-19T03:01:26.045882Z","steps":["trace[54561570] 'range keys from in-memory index tree' (duration: 543.699107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:26.045933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:25.501676Z","time spent":"544.251177ms","remote":"127.0.0.1:55632","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-02-19T03:01:26.045939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:25.725364Z","time spent":"320.560011ms","remote":"127.0.0.1:55538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-02-19T03:01:26.046245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.394186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10303"} {"level":"warn","ts":"2026-02-19T03:01:26.046282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.899741ms","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-02-19T03:01:26.046309Z","caller":"traceutil/trace.go:171","msg":"trace[1686902529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5221; }","duration":"223.969332ms","start":"2026-02-19T03:01:25.822332Z","end":"2026-02-19T03:01:26.046301Z","steps":["trace[1686902529] 'range keys from in-memory index tree' (duration: 223.109275ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:26.046303Z","caller":"traceutil/trace.go:171","msg":"trace[249039141] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5221; }","duration":"385.488768ms","start":"2026-02-19T03:01:25.660798Z","end":"2026-02-19T03:01:26.046287Z","steps":["trace[249039141] 'range keys from in-memory index tree' (duration: 384.785463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:26.046347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:25.660778Z","time spent":"385.561339ms","remote":"127.0.0.1:55558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10326,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-02-19T03:01:26.434345Z","caller":"traceutil/trace.go:171","msg":"trace[280009146] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"469.842304ms","start":"2026-02-19T03:01:25.96447Z","end":"2026-02-19T03:01:26.434313Z","steps":["trace[280009146] 'process raft request' (duration: 469.585289ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:26.434342Z","caller":"traceutil/trace.go:171","msg":"trace[109668179] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"384.163471ms","start":"2026-02-19T03:01:26.050142Z","end":"2026-02-19T03:01:26.434306Z","steps":["trace[109668179] 'process raft request' (duration: 384.070109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:26.434522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:25.964442Z","time spent":"469.986377ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:01:26.434493Z","caller":"traceutil/trace.go:171","msg":"trace[738968018] linearizableReadLoop","detail":"{readStateIndex:5426; appliedIndex:5424; }","duration":"465.912548ms","start":"2026-02-19T03:01:25.968524Z","end":"2026-02-19T03:01:26.434437Z","steps":["trace[738968018] 'read index received' (duration: 86.361778ms)","trace[738968018] 'applied index is now lower than readState.Index' (duration: 379.54792ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:01:26.434617Z","caller":"traceutil/trace.go:171","msg":"trace[1665006128] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"382.786704ms","start":"2026-02-19T03:01:26.0517Z","end":"2026-02-19T03:01:26.434487Z","steps":["trace[1665006128] 'process raft request' (duration: 382.553659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:26.434633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.050127Z","time spent":"384.387366ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T03:01:26.434763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.051693Z","time spent":"382.999178ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T03:01:26.434798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.253555ms","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-02-19T03:01:26.434883Z","caller":"traceutil/trace.go:171","msg":"trace[771602597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5225; }","duration":"466.377806ms","start":"2026-02-19T03:01:25.968492Z","end":"2026-02-19T03:01:26.43487Z","steps":["trace[771602597] 'agreement among raft nodes before linearized reading' (duration: 466.105301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:26.434977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:25.968479Z","time spent":"466.463889ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T03:01:26.466197Z","caller":"traceutil/trace.go:171","msg":"trace[1093832283] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"407.867567ms","start":"2026-02-19T03:01:26.058301Z","end":"2026-02-19T03:01:26.466169Z","steps":["trace[1093832283] 'process raft request' (duration: 407.624162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:26.466328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.058286Z","time spent":"407.969039ms","remote":"127.0.0.1:55558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10565,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:01:26.466374Z","caller":"traceutil/trace.go:171","msg":"trace[590176591] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"387.921675ms","start":"2026-02-19T03:01:26.078367Z","end":"2026-02-19T03:01:26.466288Z","steps":["trace[590176591] 'process raft request' (duration: 387.732321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:26.466473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.565289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T03:01:26.466543Z","caller":"traceutil/trace.go:171","msg":"trace[742813378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5227; }","duration":"440.690931ms","start":"2026-02-19T03:01:26.025834Z","end":"2026-02-19T03:01:26.466525Z","steps":["trace[742813378] 'agreement among raft nodes before linearized reading' (duration: 440.535379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:26.466585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.025794Z","time spent":"440.783854ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T03:01:26.466619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.068578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-07020799-1029-4282-9725-1b51242b8d73\" ","response":"range_response_count:1 size:1322"} {"level":"warn","ts":"2026-02-19T03:01:26.466648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.078326Z","time spent":"388.15692ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":553,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:01:26.466702Z","caller":"traceutil/trace.go:171","msg":"trace[1318680818] range","detail":"{range_begin:/registry/persistentvolumes/pvc-07020799-1029-4282-9725-1b51242b8d73; range_end:; response_count:1; response_revision:5227; }","duration":"417.207491ms","start":"2026-02-19T03:01:26.049475Z","end":"2026-02-19T03:01:26.466682Z","steps":["trace[1318680818] 'agreement among raft nodes before linearized reading' (duration: 416.958326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:26.466749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.049441Z","time spent":"417.298142ms","remote":"127.0.0.1:55524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-07020799-1029-4282-9725-1b51242b8d73\" "} {"level":"warn","ts":"2026-02-19T03:01:27.222071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.641697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T03:01:27.222696Z","caller":"traceutil/trace.go:171","msg":"trace[1131388088] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"749.197336ms","start":"2026-02-19T03:01:26.473467Z","end":"2026-02-19T03:01:27.222665Z","steps":["trace[1131388088] 'process raft request' (duration: 748.684387ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:27.222809Z","caller":"traceutil/trace.go:171","msg":"trace[422164612] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"747.450072ms","start":"2026-02-19T03:01:26.475332Z","end":"2026-02-19T03:01:27.222782Z","steps":["trace[422164612] 'process raft request' (duration: 746.968152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:27.222845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.473457Z","time spent":"749.314419ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1431,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:01:27.222859Z","caller":"traceutil/trace.go:171","msg":"trace[1847619321] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"748.496601ms","start":"2026-02-19T03:01:26.474307Z","end":"2026-02-19T03:01:27.222804Z","steps":["trace[1847619321] 'process raft request' (duration: 747.963251ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:27.222843Z","caller":"traceutil/trace.go:171","msg":"trace[1688360192] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"751.620733ms","start":"2026-02-19T03:01:26.471159Z","end":"2026-02-19T03:01:27.22278Z","steps":["trace[1688360192] 'process raft request' (duration: 474.17912ms)","trace[1688360192] 'compare' (duration: 276.498365ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:01:27.222916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.475323Z","time spent":"747.544593ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:01:27.222889Z","caller":"traceutil/trace.go:171","msg":"trace[38512376] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"748.91114ms","start":"2026-02-19T03:01:26.473732Z","end":"2026-02-19T03:01:27.222643Z","steps":["trace[38512376] 'process raft request' (duration: 748.499712ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:27.222951Z","caller":"traceutil/trace.go:171","msg":"trace[1485802177] linearizableReadLoop","detail":"{readStateIndex:5436; appliedIndex:5431; }","duration":"588.316083ms","start":"2026-02-19T03:01:26.63448Z","end":"2026-02-19T03:01:27.222796Z","steps":["trace[1485802177] 'read index received' (duration: 310.716017ms)","trace[1485802177] 'applied index is now lower than readState.Index' (duration: 277.598546ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:01:27.223027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.550757ms","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":"warn","ts":"2026-02-19T03:01:27.223031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.474298Z","time spent":"748.643145ms","remote":"127.0.0.1:55540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":895,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:01:27.223063Z","caller":"traceutil/trace.go:171","msg":"trace[354340986] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:5234; }","duration":"588.634659ms","start":"2026-02-19T03:01:26.634419Z","end":"2026-02-19T03:01:27.223054Z","steps":["trace[354340986] 'agreement among raft nodes before linearized reading' (duration: 588.552217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:27.223085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.634393Z","time spent":"588.686871ms","remote":"127.0.0.1:55540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":48,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-19T03:01:27.223074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.473723Z","time spent":"749.252786ms","remote":"127.0.0.1:55540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":953,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T03:01:27.2233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.075042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-19T03:01:27.223192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.471124Z","time spent":"751.845977ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:01:27.223347Z","caller":"traceutil/trace.go:171","msg":"trace[1142760901] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:5234; }","duration":"204.166532ms","start":"2026-02-19T03:01:27.019169Z","end":"2026-02-19T03:01:27.223335Z","steps":["trace[1142760901] 'agreement among raft nodes before linearized reading' (duration: 204.01772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:27.223387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.956256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8tcmh\" ","response":"range_response_count:1 size:6281"} {"level":"warn","ts":"2026-02-19T03:01:27.223391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.511789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-02-19T03:01:27.223437Z","caller":"traceutil/trace.go:171","msg":"trace[646878574] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8tcmh; range_end:; response_count:1; response_revision:5234; }","duration":"502.060258ms","start":"2026-02-19T03:01:26.721364Z","end":"2026-02-19T03:01:27.223424Z","steps":["trace[646878574] 'agreement among raft nodes before linearized reading' (duration: 501.895515ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:27.223447Z","caller":"traceutil/trace.go:171","msg":"trace[148005549] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5234; }","duration":"550.596751ms","start":"2026-02-19T03:01:26.672834Z","end":"2026-02-19T03:01:27.223431Z","steps":["trace[148005549] 'agreement among raft nodes before linearized reading' (duration: 550.358167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:27.223471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.721333Z","time spent":"502.131429ms","remote":"127.0.0.1:55558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6304,"request content":"key:\"/registry/pods/openstack/keepalived-8tcmh\" "} {"level":"warn","ts":"2026-02-19T03:01:27.223479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:01:26.672817Z","time spent":"550.655072ms","remote":"127.0.0.1:55538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-02-19T03:01:27.224334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.517211ms","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-02-19T03:01:27.224405Z","caller":"traceutil/trace.go:171","msg":"trace[150952642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5234; }","duration":"261.565412ms","start":"2026-02-19T03:01:26.96282Z","end":"2026-02-19T03:01:27.224385Z","steps":["trace[150952642] 'agreement among raft nodes before linearized reading' (duration: 260.402038ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:01:27.657841Z","caller":"traceutil/trace.go:171","msg":"trace[312164387] linearizableReadLoop","detail":"{readStateIndex:5441; appliedIndex:5440; }","duration":"116.894287ms","start":"2026-02-19T03:01:27.540921Z","end":"2026-02-19T03:01:27.657815Z","steps":["trace[312164387] 'read index received' (duration: 115.435769ms)","trace[312164387] 'applied index is now lower than readState.Index' (duration: 1.456298ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:01:27.657852Z","caller":"traceutil/trace.go:171","msg":"trace[1057756752] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"201.202625ms","start":"2026-02-19T03:01:27.456616Z","end":"2026-02-19T03:01:27.657819Z","steps":["trace[1057756752] 'process raft request' (duration: 199.873329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:01:27.658181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.234843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12017"} {"level":"info","ts":"2026-02-19T03:01:27.658286Z","caller":"traceutil/trace.go:171","msg":"trace[856661177] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5237; }","duration":"117.395767ms","start":"2026-02-19T03:01:27.540866Z","end":"2026-02-19T03:01:27.658262Z","steps":["trace[856661177] 'agreement among raft nodes before linearized reading' (duration: 117.02783ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:02:13.819901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2720} {"level":"info","ts":"2026-02-19T03:02:13.886983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2720,"took":"65.5864ms","hash":2592089577,"current-db-size-bytes":40218624,"current-db-size":"40 MB","current-db-size-in-use-bytes":30150656,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-02-19T03:02:13.887061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2592089577,"revision":2720,"compact-revision":-1} {"level":"info","ts":"2026-02-19T03:02:19.354271Z","caller":"traceutil/trace.go:171","msg":"trace[460751255] transaction","detail":"{read_only:false; response_revision:5637; number_of_response:1; }","duration":"175.317295ms","start":"2026-02-19T03:02:19.17892Z","end":"2026-02-19T03:02:19.354237Z","steps":["trace[460751255] 'process raft request' (duration: 175.139762ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:13.82613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5604} {"level":"info","ts":"2026-02-19T03:07:13.914003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5604,"took":"85.768229ms","hash":604381912,"current-db-size-bytes":40935424,"current-db-size":"41 MB","current-db-size-in-use-bytes":21213184,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-02-19T03:07:13.9141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":604381912,"revision":5604,"compact-revision":2720} {"level":"info","ts":"2026-02-19T03:07:15.108436Z","caller":"traceutil/trace.go:171","msg":"trace[705313066] transaction","detail":"{read_only:false; response_revision:7578; number_of_response:1; }","duration":"124.956885ms","start":"2026-02-19T03:07:14.983426Z","end":"2026-02-19T03:07:15.108383Z","steps":["trace[705313066] 'process raft request' (duration: 37.316209ms)","trace[705313066] 'compare' (duration: 87.401392ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:07:27.514894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.648118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8tcmh\" ","response":"range_response_count:1 size:6281"} {"level":"warn","ts":"2026-02-19T03:07:27.514914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.486933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:07:27.514972Z","caller":"traceutil/trace.go:171","msg":"trace[1193972165] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8tcmh; range_end:; response_count:1; response_revision:7775; }","duration":"134.773631ms","start":"2026-02-19T03:07:27.380186Z","end":"2026-02-19T03:07:27.514959Z","steps":["trace[1193972165] 'range keys from in-memory index tree' (duration: 134.558186ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:27.514998Z","caller":"traceutil/trace.go:171","msg":"trace[1625211960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7775; }","duration":"138.584015ms","start":"2026-02-19T03:07:27.376396Z","end":"2026-02-19T03:07:27.51498Z","steps":["trace[1625211960] 'range keys from in-memory index tree' (duration: 138.391722ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:28.559912Z","caller":"traceutil/trace.go:171","msg":"trace[2071993065] transaction","detail":"{read_only:false; response_revision:7782; number_of_response:1; }","duration":"138.364391ms","start":"2026-02-19T03:07:28.421504Z","end":"2026-02-19T03:07:28.559868Z","steps":["trace[2071993065] 'process raft request' (duration: 59.513149ms)","trace[2071993065] 'compare' (duration: 78.502345ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:07:29.622795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.421731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:07:29.622916Z","caller":"traceutil/trace.go:171","msg":"trace[47809937] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:7784; }","duration":"253.595306ms","start":"2026-02-19T03:07:29.369291Z","end":"2026-02-19T03:07:29.622887Z","steps":["trace[47809937] 'count revisions from in-memory index tree' (duration: 253.336479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:29.622995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.862762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:07:29.623122Z","caller":"traceutil/trace.go:171","msg":"trace[1431458251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7784; }","duration":"246.043736ms","start":"2026-02-19T03:07:29.377055Z","end":"2026-02-19T03:07:29.623099Z","steps":["trace[1431458251] 'range keys from in-memory index tree' (duration: 245.780201ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:29.623141Z","caller":"traceutil/trace.go:171","msg":"trace[1921972482] transaction","detail":"{read_only:false; response_revision:7785; number_of_response:1; }","duration":"100.9092ms","start":"2026-02-19T03:07:29.522207Z","end":"2026-02-19T03:07:29.623116Z","steps":["trace[1921972482] 'process raft request' (duration: 99.94065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:32.136778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674848113914506,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T03:07:32.190364Z","caller":"traceutil/trace.go:171","msg":"trace[1780222079] transaction","detail":"{read_only:false; response_revision:7795; number_of_response:1; }","duration":"562.637689ms","start":"2026-02-19T03:07:31.627691Z","end":"2026-02-19T03:07:32.190329Z","steps":["trace[1780222079] 'process raft request' (duration: 562.404764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:32.190572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:07:31.62766Z","time spent":"562.805122ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:07:32.190828Z","caller":"traceutil/trace.go:171","msg":"trace[944606844] transaction","detail":"{read_only:false; response_revision:7796; number_of_response:1; }","duration":"339.072809ms","start":"2026-02-19T03:07:31.851736Z","end":"2026-02-19T03:07:32.190809Z","steps":["trace[944606844] 'process raft request' (duration: 338.933626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:32.190941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:07:31.851709Z","time spent":"339.18123ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:07:32.190986Z","caller":"traceutil/trace.go:171","msg":"trace[495208606] linearizableReadLoop","detail":"{readStateIndex:8088; appliedIndex:8085; }","duration":"555.238182ms","start":"2026-02-19T03:07:31.635717Z","end":"2026-02-19T03:07:32.190955Z","steps":["trace[495208606] 'read index received' (duration: 554.381195ms)","trace[495208606] 'applied index is now lower than readState.Index' (duration: 854.877µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:07:32.191118Z","caller":"traceutil/trace.go:171","msg":"trace[463625965] transaction","detail":"{read_only:false; response_revision:7797; number_of_response:1; }","duration":"232.16861ms","start":"2026-02-19T03:07:31.958929Z","end":"2026-02-19T03:07:32.191098Z","steps":["trace[463625965] 'process raft request' (duration: 231.829204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:32.191197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.479416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8tcmh\" ","response":"range_response_count:1 size:6281"} {"level":"info","ts":"2026-02-19T03:07:32.191228Z","caller":"traceutil/trace.go:171","msg":"trace[1484377355] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8tcmh; range_end:; response_count:1; response_revision:7797; }","duration":"555.559388ms","start":"2026-02-19T03:07:31.635661Z","end":"2026-02-19T03:07:32.19122Z","steps":["trace[1484377355] 'agreement among raft nodes before linearized reading' (duration: 555.468746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:32.191257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:07:31.635641Z","time spent":"555.610718ms","remote":"127.0.0.1:55558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6304,"request content":"key:\"/registry/pods/openstack/keepalived-8tcmh\" "} {"level":"warn","ts":"2026-02-19T03:07:32.191236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.468938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T03:07:32.191307Z","caller":"traceutil/trace.go:171","msg":"trace[428461989] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:7797; }","duration":"332.585029ms","start":"2026-02-19T03:07:31.858705Z","end":"2026-02-19T03:07:32.19129Z","steps":["trace[428461989] 'agreement among raft nodes before linearized reading' (duration: 332.465467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:32.191347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:07:31.858683Z","time spent":"332.657181ms","remote":"127.0.0.1:44442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-02-19T03:07:42.450361Z","caller":"traceutil/trace.go:171","msg":"trace[279215761] transaction","detail":"{read_only:false; response_revision:7841; number_of_response:1; }","duration":"221.03485ms","start":"2026-02-19T03:07:42.229309Z","end":"2026-02-19T03:07:42.450344Z","steps":["trace[279215761] 'process raft request' (duration: 220.937297ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:42.48163Z","caller":"traceutil/trace.go:171","msg":"trace[1935872262] transaction","detail":"{read_only:false; response_revision:7842; number_of_response:1; }","duration":"252.020524ms","start":"2026-02-19T03:07:42.229574Z","end":"2026-02-19T03:07:42.481594Z","steps":["trace[1935872262] 'process raft request' (duration: 251.82289ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:42.482011Z","caller":"traceutil/trace.go:171","msg":"trace[1640278529] transaction","detail":"{read_only:false; response_revision:7843; number_of_response:1; }","duration":"252.240208ms","start":"2026-02-19T03:07:42.229751Z","end":"2026-02-19T03:07:42.481991Z","steps":["trace[1640278529] 'process raft request' (duration: 251.79622ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:42.505646Z","caller":"traceutil/trace.go:171","msg":"trace[583534474] linearizableReadLoop","detail":"{readStateIndex:8136; appliedIndex:8134; }","duration":"258.269787ms","start":"2026-02-19T03:07:42.247349Z","end":"2026-02-19T03:07:42.505619Z","steps":["trace[583534474] 'read index received' (duration: 202.990573ms)","trace[583534474] 'applied index is now lower than readState.Index' (duration: 55.277694ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:07:42.505839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.646968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T03:07:42.505831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.444931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8tcmh\" ","response":"range_response_count:1 size:6281"} {"level":"info","ts":"2026-02-19T03:07:42.505873Z","caller":"traceutil/trace.go:171","msg":"trace[715068574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7843; }","duration":"130.74014ms","start":"2026-02-19T03:07:42.375123Z","end":"2026-02-19T03:07:42.505863Z","steps":["trace[715068574] 'agreement among raft nodes before linearized reading' (duration: 130.659658ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:42.505892Z","caller":"traceutil/trace.go:171","msg":"trace[1619809189] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8tcmh; range_end:; response_count:1; response_revision:7843; }","duration":"258.561103ms","start":"2026-02-19T03:07:42.247315Z","end":"2026-02-19T03:07:42.505877Z","steps":["trace[1619809189] 'agreement among raft nodes before linearized reading' (duration: 258.4103ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:43.578239Z","caller":"traceutil/trace.go:171","msg":"trace[290487354] linearizableReadLoop","detail":"{readStateIndex:8141; appliedIndex:8140; }","duration":"201.626885ms","start":"2026-02-19T03:07:43.376581Z","end":"2026-02-19T03:07:43.578208Z","steps":["trace[290487354] 'read index received' (duration: 201.399241ms)","trace[290487354] 'applied index is now lower than readState.Index' (duration: 226.654µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:07:43.578306Z","caller":"traceutil/trace.go:171","msg":"trace[1009496125] transaction","detail":"{read_only:false; response_revision:7847; number_of_response:1; }","duration":"248.053694ms","start":"2026-02-19T03:07:43.330211Z","end":"2026-02-19T03:07:43.578264Z","steps":["trace[1009496125] 'process raft request' (duration: 247.84633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:43.578406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.802058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:07:43.578535Z","caller":"traceutil/trace.go:171","msg":"trace[120375312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7847; }","duration":"201.966261ms","start":"2026-02-19T03:07:43.376556Z","end":"2026-02-19T03:07:43.578522Z","steps":["trace[120375312] 'agreement among raft nodes before linearized reading' (duration: 201.799318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:43.578625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.054063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-02-19T03:07:43.578648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.064489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T03:07:43.578717Z","caller":"traceutil/trace.go:171","msg":"trace[1082383811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7847; }","duration":"200.223457ms","start":"2026-02-19T03:07:43.378474Z","end":"2026-02-19T03:07:43.578697Z","steps":["trace[1082383811] 'agreement among raft nodes before linearized reading' (duration: 199.974432ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:43.578793Z","caller":"traceutil/trace.go:171","msg":"trace[1436665235] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:7847; }","duration":"176.223072ms","start":"2026-02-19T03:07:43.402527Z","end":"2026-02-19T03:07:43.57875Z","steps":["trace[1436665235] 'agreement among raft nodes before linearized reading' (duration: 175.945656ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:44.590325Z","caller":"traceutil/trace.go:171","msg":"trace[1292341864] linearizableReadLoop","detail":"{readStateIndex:8144; appliedIndex:8143; }","duration":"213.090292ms","start":"2026-02-19T03:07:44.377208Z","end":"2026-02-19T03:07:44.590299Z","steps":["trace[1292341864] 'read index received' (duration: 212.930609ms)","trace[1292341864] 'applied index is now lower than readState.Index' (duration: 158.553µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:07:44.590394Z","caller":"traceutil/trace.go:171","msg":"trace[60637075] transaction","detail":"{read_only:false; response_revision:7850; number_of_response:1; }","duration":"302.692958ms","start":"2026-02-19T03:07:44.287646Z","end":"2026-02-19T03:07:44.590339Z","steps":["trace[60637075] 'process raft request' (duration: 302.492334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:44.590483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.264886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:07:44.590519Z","caller":"traceutil/trace.go:171","msg":"trace[1821795299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7850; }","duration":"213.359937ms","start":"2026-02-19T03:07:44.377147Z","end":"2026-02-19T03:07:44.590507Z","steps":["trace[1821795299] 'agreement among raft nodes before linearized reading' (duration: 213.287666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:44.590573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:07:44.287618Z","time spent":"302.857321ms","remote":"127.0.0.1:55456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T03:07:44.801995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.326961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T03:07:44.802202Z","caller":"traceutil/trace.go:171","msg":"trace[131194513] transaction","detail":"{read_only:false; response_revision:7852; number_of_response:1; }","duration":"315.682725ms","start":"2026-02-19T03:07:44.486498Z","end":"2026-02-19T03:07:44.802181Z","steps":["trace[131194513] 'process raft request' (duration: 315.604904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:44.802277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:07:44.486468Z","time spent":"315.776947ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:07:44.802254Z","caller":"traceutil/trace.go:171","msg":"trace[363317607] transaction","detail":"{read_only:false; response_revision:7851; number_of_response:1; }","duration":"344.019147ms","start":"2026-02-19T03:07:44.458196Z","end":"2026-02-19T03:07:44.802215Z","steps":["trace[363317607] 'process raft request' (duration: 155.38723ms)","trace[363317607] 'compare' (duration: 188.155128ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:07:44.802415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:07:44.458177Z","time spent":"344.152319ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:07:44.802402Z","caller":"traceutil/trace.go:171","msg":"trace[1943740975] transaction","detail":"{read_only:false; response_revision:7853; number_of_response:1; }","duration":"315.339508ms","start":"2026-02-19T03:07:44.487012Z","end":"2026-02-19T03:07:44.802352Z","steps":["trace[1943740975] 'process raft request' (duration: 315.141384ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:44.802435Z","caller":"traceutil/trace.go:171","msg":"trace[246807468] linearizableReadLoop","detail":"{readStateIndex:8147; appliedIndex:8144; }","duration":"211.987571ms","start":"2026-02-19T03:07:44.590403Z","end":"2026-02-19T03:07:44.80239Z","steps":["trace[246807468] 'read index received' (duration: 23.103928ms)","trace[246807468] 'applied index is now lower than readState.Index' (duration: 188.878982ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:07:44.802584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.144429ms","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-02-19T03:07:44.802582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:07:44.487004Z","time spent":"315.4822ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:07:44.802633Z","caller":"traceutil/trace.go:171","msg":"trace[1421159058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7854; }","duration":"233.225631ms","start":"2026-02-19T03:07:44.569396Z","end":"2026-02-19T03:07:44.802622Z","steps":["trace[1421159058] 'agreement among raft nodes before linearized reading' (duration: 233.080368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:44.802578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.535198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8tcmh\" ","response":"range_response_count:1 size:6281"} {"level":"warn","ts":"2026-02-19T03:07:44.802639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.705504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T03:07:44.802705Z","caller":"traceutil/trace.go:171","msg":"trace[913231074] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8tcmh; range_end:; response_count:1; response_revision:7854; }","duration":"228.69756ms","start":"2026-02-19T03:07:44.573995Z","end":"2026-02-19T03:07:44.802692Z","steps":["trace[913231074] 'agreement among raft nodes before linearized reading' (duration: 228.447776ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:44.802741Z","caller":"traceutil/trace.go:171","msg":"trace[216536347] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:7854; }","duration":"378.886747ms","start":"2026-02-19T03:07:44.423831Z","end":"2026-02-19T03:07:44.802717Z","steps":["trace[216536347] 'agreement among raft nodes before linearized reading' (duration: 378.717354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:44.802836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.319424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414346"} {"level":"warn","ts":"2026-02-19T03:07:44.802839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.957651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-19T03:07:44.802839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.811047ms","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-02-19T03:07:44.802878Z","caller":"traceutil/trace.go:171","msg":"trace[410142429] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:7854; }","duration":"118.401646ms","start":"2026-02-19T03:07:44.684466Z","end":"2026-02-19T03:07:44.802867Z","steps":["trace[410142429] 'agreement among raft nodes before linearized reading' (duration: 118.156441ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:44.802898Z","caller":"traceutil/trace.go:171","msg":"trace[669302915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7854; }","duration":"201.019823ms","start":"2026-02-19T03:07:44.601853Z","end":"2026-02-19T03:07:44.802873Z","steps":["trace[669302915] 'agreement among raft nodes before linearized reading' (duration: 200.86581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:44.80287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:07:44.423811Z","time spent":"379.02147ms","remote":"127.0.0.1:55878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":20,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-02-19T03:07:44.802928Z","caller":"traceutil/trace.go:171","msg":"trace[1930289024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7854; }","duration":"156.93973ms","start":"2026-02-19T03:07:44.645972Z","end":"2026-02-19T03:07:44.802912Z","steps":["trace[1930289024] 'agreement among raft nodes before linearized reading' (duration: 156.733876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:44.802669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.198164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:07:44.803027Z","caller":"traceutil/trace.go:171","msg":"trace[723420987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7854; }","duration":"211.586872ms","start":"2026-02-19T03:07:44.5914Z","end":"2026-02-19T03:07:44.802987Z","steps":["trace[723420987] 'agreement among raft nodes before linearized reading' (duration: 211.194535ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:44.919343Z","caller":"traceutil/trace.go:171","msg":"trace[1472387218] linearizableReadLoop","detail":"{readStateIndex:8150; appliedIndex:8148; }","duration":"112.124752ms","start":"2026-02-19T03:07:44.807187Z","end":"2026-02-19T03:07:44.919312Z","steps":["trace[1472387218] 'read index received' (duration: 105.399008ms)","trace[1472387218] 'applied index is now lower than readState.Index' (duration: 6.724113ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:07:44.91946Z","caller":"traceutil/trace.go:171","msg":"trace[527762993] transaction","detail":"{read_only:false; response_revision:7856; number_of_response:1; }","duration":"112.259994ms","start":"2026-02-19T03:07:44.807162Z","end":"2026-02-19T03:07:44.919422Z","steps":["trace[527762993] 'process raft request' (duration: 112.002429ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:44.919623Z","caller":"traceutil/trace.go:171","msg":"trace[1430844505] transaction","detail":"{read_only:false; response_revision:7857; number_of_response:1; }","duration":"112.06076ms","start":"2026-02-19T03:07:44.807531Z","end":"2026-02-19T03:07:44.919592Z","steps":["trace[1430844505] 'process raft request' (duration: 111.691983ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:44.91978Z","caller":"traceutil/trace.go:171","msg":"trace[1209590067] transaction","detail":"{read_only:false; response_revision:7858; number_of_response:1; }","duration":"111.917868ms","start":"2026-02-19T03:07:44.807829Z","end":"2026-02-19T03:07:44.919746Z","steps":["trace[1209590067] 'process raft request' (duration: 111.437939ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:44.919847Z","caller":"traceutil/trace.go:171","msg":"trace[887909111] transaction","detail":"{read_only:false; response_revision:7855; number_of_response:1; }","duration":"114.229314ms","start":"2026-02-19T03:07:44.805043Z","end":"2026-02-19T03:07:44.919272Z","steps":["trace[887909111] 'process raft request' (duration: 107.525881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:07:44.920239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.018319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:219538"} {"level":"info","ts":"2026-02-19T03:07:44.920363Z","caller":"traceutil/trace.go:171","msg":"trace[1697182352] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7858; }","duration":"113.183892ms","start":"2026-02-19T03:07:44.807162Z","end":"2026-02-19T03:07:44.920346Z","steps":["trace[1697182352] 'agreement among raft nodes before linearized reading' (duration: 112.344535ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:45.129273Z","caller":"traceutil/trace.go:171","msg":"trace[591702585] transaction","detail":"{read_only:false; response_revision:7859; number_of_response:1; }","duration":"115.516108ms","start":"2026-02-19T03:07:45.013727Z","end":"2026-02-19T03:07:45.129243Z","steps":["trace[591702585] 'process raft request' (duration: 110.239854ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:45.129281Z","caller":"traceutil/trace.go:171","msg":"trace[911606390] linearizableReadLoop","detail":"{readStateIndex:8153; appliedIndex:8152; }","duration":"114.810404ms","start":"2026-02-19T03:07:45.014435Z","end":"2026-02-19T03:07:45.129245Z","steps":["trace[911606390] 'read index received' (duration: 109.479009ms)","trace[911606390] 'applied index is now lower than readState.Index' (duration: 5.329845ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:07:45.129422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.970738ms","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-02-19T03:07:45.129515Z","caller":"traceutil/trace.go:171","msg":"trace[2077557122] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7859; }","duration":"115.08018ms","start":"2026-02-19T03:07:45.014384Z","end":"2026-02-19T03:07:45.129464Z","steps":["trace[2077557122] 'agreement among raft nodes before linearized reading' (duration: 114.957787ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:07:45.384095Z","caller":"traceutil/trace.go:171","msg":"trace[852567816] transaction","detail":"{read_only:false; response_revision:7861; number_of_response:1; }","duration":"214.653963ms","start":"2026-02-19T03:07:45.169361Z","end":"2026-02-19T03:07:45.384015Z","steps":["trace[852567816] 'process raft request' (duration: 214.302697ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:08:09.015575Z","caller":"traceutil/trace.go:171","msg":"trace[1564743688] transaction","detail":"{read_only:false; response_revision:8026; number_of_response:1; }","duration":"132.201354ms","start":"2026-02-19T03:08:08.883333Z","end":"2026-02-19T03:08:09.015534Z","steps":["trace[1564743688] 'process raft request' (duration: 132.014741ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:08:09.016261Z","caller":"traceutil/trace.go:171","msg":"trace[2039482348] linearizableReadLoop","detail":"{readStateIndex:8330; appliedIndex:8327; }","duration":"107.580466ms","start":"2026-02-19T03:08:08.908655Z","end":"2026-02-19T03:08:09.016236Z","steps":["trace[2039482348] 'read index received' (duration: 106.653997ms)","trace[2039482348] 'applied index is now lower than readState.Index' (duration: 925.449µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:08:09.016305Z","caller":"traceutil/trace.go:171","msg":"trace[1837087501] transaction","detail":"{read_only:false; response_revision:8028; number_of_response:1; }","duration":"128.94181ms","start":"2026-02-19T03:08:08.887343Z","end":"2026-02-19T03:08:09.016285Z","steps":["trace[1837087501] 'process raft request' (duration: 128.851288ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:08:09.016328Z","caller":"traceutil/trace.go:171","msg":"trace[244912235] transaction","detail":"{read_only:false; response_revision:8027; number_of_response:1; }","duration":"131.648114ms","start":"2026-02-19T03:08:08.884637Z","end":"2026-02-19T03:08:09.016285Z","steps":["trace[244912235] 'process raft request' (duration: 131.408979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:08:09.016536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.875992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-csi-config\" ","response":"range_response_count:1 size:512"} {"level":"info","ts":"2026-02-19T03:08:09.016587Z","caller":"traceutil/trace.go:171","msg":"trace[1085284290] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-csi-config; range_end:; response_count:1; response_revision:8028; }","duration":"107.986515ms","start":"2026-02-19T03:08:08.908586Z","end":"2026-02-19T03:08:09.016572Z","steps":["trace[1085284290] 'agreement among raft nodes before linearized reading' (duration: 107.788971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:08:13.627575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674848113915339,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T03:08:14.112031Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.002717685s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T03:08:14.112662Z","caller":"traceutil/trace.go:171","msg":"trace[1029286225] transaction","detail":"{read_only:false; response_revision:8045; number_of_response:1; }","duration":"1.003419468s","start":"2026-02-19T03:08:13.109211Z","end":"2026-02-19T03:08:14.112631Z","steps":["trace[1029286225] 'process raft request' (duration: 1.003184174s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:08:14.112874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:08:13.10919Z","time spent":"1.003574141s","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:08:14.114645Z","caller":"traceutil/trace.go:171","msg":"trace[764523658] transaction","detail":"{read_only:false; response_revision:8046; number_of_response:1; }","duration":"995.498981ms","start":"2026-02-19T03:08:13.118967Z","end":"2026-02-19T03:08:14.114466Z","steps":["trace[764523658] 'process raft request' (duration: 995.320318ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:08:14.114685Z","caller":"traceutil/trace.go:171","msg":"trace[2067933765] linearizableReadLoop","detail":"{readStateIndex:8350; appliedIndex:8347; }","duration":"987.553564ms","start":"2026-02-19T03:08:13.127114Z","end":"2026-02-19T03:08:14.114668Z","steps":["trace[2067933765] 'read index received' (duration: 985.198017ms)","trace[2067933765] 'applied index is now lower than readState.Index' (duration: 2.353227ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:08:14.114779Z","caller":"traceutil/trace.go:171","msg":"trace[721697840] transaction","detail":"{read_only:false; response_revision:8047; number_of_response:1; }","duration":"995.562633ms","start":"2026-02-19T03:08:13.119207Z","end":"2026-02-19T03:08:14.114769Z","steps":["trace[721697840] 'process raft request' (duration: 995.211776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:08:14.114808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:08:13.118952Z","time spent":"995.751617ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T03:08:14.114885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:08:13.119192Z","time spent":"995.620514ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":553,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T03:08:14.115114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.929003ms","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-02-19T03:08:14.115173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.968816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T03:08:14.115205Z","caller":"traceutil/trace.go:171","msg":"trace[531473076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8047; }","duration":"975.054035ms","start":"2026-02-19T03:08:13.14013Z","end":"2026-02-19T03:08:14.115184Z","steps":["trace[531473076] 'agreement among raft nodes before linearized reading' (duration: 974.81947ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:08:14.115218Z","caller":"traceutil/trace.go:171","msg":"trace[1119900595] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:8047; }","duration":"630.079187ms","start":"2026-02-19T03:08:13.485125Z","end":"2026-02-19T03:08:14.115204Z","steps":["trace[1119900595] 'agreement among raft nodes before linearized reading' (duration: 629.996236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:08:14.115254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:08:13.485106Z","time spent":"630.139528ms","remote":"127.0.0.1:39506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":22,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-02-19T03:08:14.115302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.737924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:08:14.115335Z","caller":"traceutil/trace.go:171","msg":"trace[1029815944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8047; }","duration":"738.792566ms","start":"2026-02-19T03:08:13.376532Z","end":"2026-02-19T03:08:14.115324Z","steps":["trace[1029815944] 'agreement among raft nodes before linearized reading' (duration: 738.745535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:08:14.115344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.085762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-02-19T03:08:14.115363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:08:13.376518Z","time spent":"738.840286ms","remote":"127.0.0.1:50856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T03:08:14.115387Z","caller":"traceutil/trace.go:171","msg":"trace[1450493310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8047; }","duration":"265.176524ms","start":"2026-02-19T03:08:13.8502Z","end":"2026-02-19T03:08:14.115376Z","steps":["trace[1450493310] 'agreement among raft nodes before linearized reading' (duration: 265.00646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:08:14.115511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.130132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-19T03:08:14.11552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.40433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8tcmh\" ","response":"range_response_count:1 size:6281"} {"level":"info","ts":"2026-02-19T03:08:14.115551Z","caller":"traceutil/trace.go:171","msg":"trace[1302237608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8047; }","duration":"269.209994ms","start":"2026-02-19T03:08:13.846331Z","end":"2026-02-19T03:08:14.115541Z","steps":["trace[1302237608] 'agreement among raft nodes before linearized reading' (duration: 269.071411ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:08:14.115557Z","caller":"traceutil/trace.go:171","msg":"trace[145023020] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8tcmh; range_end:; response_count:1; response_revision:8047; }","duration":"988.466851ms","start":"2026-02-19T03:08:13.127051Z","end":"2026-02-19T03:08:14.115547Z","steps":["trace[145023020] 'agreement among raft nodes before linearized reading' (duration: 988.39069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:08:14.115592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:08:13.12704Z","time spent":"988.544653ms","remote":"127.0.0.1:55558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6304,"request content":"key:\"/registry/pods/openstack/keepalived-8tcmh\" "} {"level":"warn","ts":"2026-02-19T03:08:14.115255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:08:13.14012Z","time spent":"975.123836ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T03:08:14.115622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.144389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-19T03:08:14.115656Z","caller":"traceutil/trace.go:171","msg":"trace[588820282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8047; }","duration":"959.20257ms","start":"2026-02-19T03:08:13.156443Z","end":"2026-02-19T03:08:14.115646Z","steps":["trace[588820282] 'agreement among raft nodes before linearized reading' (duration: 959.112608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:08:14.115694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:08:13.156429Z","time spent":"959.259821ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T03:08:14.115809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.511497ms","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-02-19T03:08:14.115841Z","caller":"traceutil/trace.go:171","msg":"trace[312637828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8047; }","duration":"960.559158ms","start":"2026-02-19T03:08:13.155271Z","end":"2026-02-19T03:08:14.115831Z","steps":["trace[312637828] 'agreement among raft nodes before linearized reading' (duration: 960.485016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:08:14.115865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:08:13.155262Z","time spent":"960.598048ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-19T03:08:26.001731Z","caller":"traceutil/trace.go:171","msg":"trace[1532294565] transaction","detail":"{read_only:false; response_revision:8143; number_of_response:1; }","duration":"296.228471ms","start":"2026-02-19T03:08:25.705465Z","end":"2026-02-19T03:08:26.001694Z","steps":["trace[1532294565] 'process raft request' (duration: 295.964476ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:08:26.007811Z","caller":"traceutil/trace.go:171","msg":"trace[1801314243] linearizableReadLoop","detail":"{readStateIndex:8451; appliedIndex:8449; }","duration":"172.950614ms","start":"2026-02-19T03:08:25.834823Z","end":"2026-02-19T03:08:26.007774Z","steps":["trace[1801314243] 'read index received' (duration: 166.628048ms)","trace[1801314243] 'applied index is now lower than readState.Index' (duration: 6.320965ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:08:26.008318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.841217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T03:08:26.00837Z","caller":"traceutil/trace.go:171","msg":"trace[955615823] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:8144; }","duration":"128.97381ms","start":"2026-02-19T03:08:25.879384Z","end":"2026-02-19T03:08:26.008358Z","steps":["trace[955615823] 'agreement among raft nodes before linearized reading' (duration: 128.844407ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:08:26.00829Z","caller":"traceutil/trace.go:171","msg":"trace[1809559444] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8144; }","duration":"302.507035ms","start":"2026-02-19T03:08:25.705745Z","end":"2026-02-19T03:08:26.008252Z","steps":["trace[1809559444] 'process raft request' (duration: 301.800591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:08:26.008446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.602086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12017"} {"level":"info","ts":"2026-02-19T03:08:26.008505Z","caller":"traceutil/trace.go:171","msg":"trace[1188208726] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8144; }","duration":"173.702478ms","start":"2026-02-19T03:08:25.834787Z","end":"2026-02-19T03:08:26.00849Z","steps":["trace[1188208726] 'agreement among raft nodes before linearized reading' (duration: 173.483704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:08:26.008707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:08:25.705724Z","time spent":"302.849842ms","remote":"127.0.0.1:35420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-02-19T03:08:26.997072Z","caller":"traceutil/trace.go:171","msg":"trace[1447784405] transaction","detail":"{read_only:false; response_revision:8156; number_of_response:1; }","duration":"226.808432ms","start":"2026-02-19T03:08:26.770202Z","end":"2026-02-19T03:08:26.99701Z","steps":["trace[1447784405] 'process raft request' (duration: 215.166561ms)","trace[1447784405] 'compare' (duration: 11.364535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:08:27.316918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.258732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T03:08:27.317025Z","caller":"traceutil/trace.go:171","msg":"trace[1183947019] linearizableReadLoop","detail":"{readStateIndex:8464; appliedIndex:8463; }","duration":"274.668313ms","start":"2026-02-19T03:08:27.042337Z","end":"2026-02-19T03:08:27.317005Z","steps":["trace[1183947019] 'read index received' (duration: 62.209146ms)","trace[1183947019] 'applied index is now lower than readState.Index' (duration: 212.458157ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:08:27.317157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.823276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-19T03:08:27.31719Z","caller":"traceutil/trace.go:171","msg":"trace[941095949] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:8157; }","duration":"274.899718ms","start":"2026-02-19T03:08:27.042283Z","end":"2026-02-19T03:08:27.317183Z","steps":["trace[941095949] 'agreement among raft nodes before linearized reading' (duration: 274.761365ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:08:27.317262Z","caller":"traceutil/trace.go:171","msg":"trace[1760024825] transaction","detail":"{read_only:false; response_revision:8157; number_of_response:1; }","duration":"306.080127ms","start":"2026-02-19T03:08:27.011105Z","end":"2026-02-19T03:08:27.317185Z","steps":["trace[1760024825] 'process raft request' (duration: 93.495737ms)","trace[1760024825] 'compare' (duration: 212.13528ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:08:27.317416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:08:27.011081Z","time spent":"306.26405ms","remote":"127.0.0.1:55474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":583,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T03:08:38.940639Z","caller":"traceutil/trace.go:171","msg":"trace[1339752315] transaction","detail":"{read_only:false; response_revision:8249; number_of_response:1; }","duration":"108.114416ms","start":"2026-02-19T03:08:38.832492Z","end":"2026-02-19T03:08:38.940606Z","steps":["trace[1339752315] 'process raft request' (duration: 107.953683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:09:37.526848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.60481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-02-19T03:09:37.526967Z","caller":"traceutil/trace.go:171","msg":"trace[1532591028] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8833; }","duration":"191.777724ms","start":"2026-02-19T03:09:37.335167Z","end":"2026-02-19T03:09:37.526945Z","steps":["trace[1532591028] 'range keys from in-memory index tree' (duration: 191.402947ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:09:37.561732Z","caller":"traceutil/trace.go:171","msg":"trace[331200819] linearizableReadLoop","detail":"{readStateIndex:9161; appliedIndex:9160; }","duration":"128.606661ms","start":"2026-02-19T03:09:37.433101Z","end":"2026-02-19T03:09:37.561708Z","steps":["trace[331200819] 'read index received' (duration: 128.467908ms)","trace[331200819] 'applied index is now lower than readState.Index' (duration: 137.723µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:09:37.56179Z","caller":"traceutil/trace.go:171","msg":"trace[1719225322] transaction","detail":"{read_only:false; response_revision:8834; number_of_response:1; }","duration":"161.917953ms","start":"2026-02-19T03:09:37.399832Z","end":"2026-02-19T03:09:37.56175Z","steps":["trace[1719225322] 'process raft request' (duration: 161.732449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:09:37.562004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.891357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12017"} {"level":"info","ts":"2026-02-19T03:09:37.562046Z","caller":"traceutil/trace.go:171","msg":"trace[1412537492] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8834; }","duration":"128.995029ms","start":"2026-02-19T03:09:37.433041Z","end":"2026-02-19T03:09:37.562036Z","steps":["trace[1412537492] 'agreement among raft nodes before linearized reading' (duration: 128.783575ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:11:37.967659Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"791a7e25b533cd26","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-19T03:11:37.973951Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-19T03:11:37.974099Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-19T03:12:11.586861Z","caller":"traceutil/trace.go:171","msg":"trace[499221460] transaction","detail":"{read_only:false; response_revision:9997; number_of_response:1; }","duration":"101.440062ms","start":"2026-02-19T03:12:11.485398Z","end":"2026-02-19T03:12:11.586838Z","steps":["trace[499221460] 'process raft request' (duration: 101.324479ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:12:13.832517Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7571} {"level":"info","ts":"2026-02-19T03:12:13.888896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7571,"took":"54.175806ms","hash":2663598648,"current-db-size-bytes":40935424,"current-db-size":"41 MB","current-db-size-in-use-bytes":25427968,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-02-19T03:12:13.888972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2663598648,"revision":7571,"compact-revision":5604} {"level":"warn","ts":"2026-02-19T03:12:57.90369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.986229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T03:12:57.903901Z","caller":"traceutil/trace.go:171","msg":"trace[96586586] linearizableReadLoop","detail":"{readStateIndex:10826; appliedIndex:10825; }","duration":"513.348499ms","start":"2026-02-19T03:12:57.390505Z","end":"2026-02-19T03:12:57.903853Z","steps":["trace[96586586] 'read index received' (duration: 251.078074ms)","trace[96586586] 'applied index is now lower than readState.Index' (duration: 262.268375ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:12:57.904272Z","caller":"traceutil/trace.go:171","msg":"trace[333037420] transaction","detail":"{read_only:false; response_revision:10449; number_of_response:1; }","duration":"578.43426ms","start":"2026-02-19T03:12:57.325798Z","end":"2026-02-19T03:12:57.904232Z","steps":["trace[333037420] 'process raft request' (duration: 315.758567ms)","trace[333037420] 'compare' (duration: 261.556801ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:12:57.904435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.939633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T03:12:57.90446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.742903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T03:12:57.904494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.987689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T03:12:57.904529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.843616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:4960"} {"level":"info","ts":"2026-02-19T03:12:57.904587Z","caller":"traceutil/trace.go:171","msg":"trace[1528313842] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:10449; }","duration":"335.116231ms","start":"2026-02-19T03:12:57.56945Z","end":"2026-02-19T03:12:57.904566Z","steps":["trace[1528313842] 'agreement among raft nodes before linearized reading' (duration: 334.923917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:12:57.904617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.008449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T03:12:57.904625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:12:57.569442Z","time spent":"335.174913ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" "} {"level":"warn","ts":"2026-02-19T03:12:57.904587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.04506ms","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-02-19T03:12:57.90464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.348939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:12:57.904651Z","caller":"traceutil/trace.go:171","msg":"trace[1463850099] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:10449; }","duration":"336.08056ms","start":"2026-02-19T03:12:57.568562Z","end":"2026-02-19T03:12:57.904642Z","steps":["trace[1463850099] 'agreement among raft nodes before linearized reading' (duration: 336.031899ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:12:57.904684Z","caller":"traceutil/trace.go:171","msg":"trace[1494259555] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:10449; }","duration":"244.409611ms","start":"2026-02-19T03:12:57.660257Z","end":"2026-02-19T03:12:57.904667Z","steps":["trace[1494259555] 'agreement among raft nodes before linearized reading' (duration: 244.34844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:12:57.904686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:12:57.568546Z","time spent":"336.133211ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"info","ts":"2026-02-19T03:12:57.904559Z","caller":"traceutil/trace.go:171","msg":"trace[1124966362] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:10449; }","duration":"335.889526ms","start":"2026-02-19T03:12:57.568645Z","end":"2026-02-19T03:12:57.904535Z","steps":["trace[1124966362] 'agreement among raft nodes before linearized reading' (duration: 335.717253ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:12:57.904685Z","caller":"traceutil/trace.go:171","msg":"trace[423280780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10449; }","duration":"203.162583ms","start":"2026-02-19T03:12:57.701498Z","end":"2026-02-19T03:12:57.90466Z","steps":["trace[423280780] 'agreement among raft nodes before linearized reading' (duration: 202.939218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:12:57.904636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:12:57.325769Z","time spent":"578.633125ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T03:12:57.904627Z","caller":"traceutil/trace.go:171","msg":"trace[1013745823] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:10449; }","duration":"330.969459ms","start":"2026-02-19T03:12:57.573636Z","end":"2026-02-19T03:12:57.904606Z","steps":["trace[1013745823] 'agreement among raft nodes before linearized reading' (duration: 330.712314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:12:57.904789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:12:57.56862Z","time spent":"336.13388ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"warn","ts":"2026-02-19T03:12:57.904866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:12:57.573627Z","time spent":"331.216373ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":4983,"request content":"key:\"/registry/jobs/openstack/cinder-db-sync\" "} {"level":"info","ts":"2026-02-19T03:12:57.904503Z","caller":"traceutil/trace.go:171","msg":"trace[117360132] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:10449; }","duration":"488.055226ms","start":"2026-02-19T03:12:57.416429Z","end":"2026-02-19T03:12:57.904484Z","steps":["trace[117360132] 'agreement among raft nodes before linearized reading' (duration: 487.915283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:12:57.904908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.646244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T03:12:57.904975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:12:57.416414Z","time spent":"488.545105ms","remote":"127.0.0.1:35462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-19T03:12:57.904556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.901826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:12:57.905084Z","caller":"traceutil/trace.go:171","msg":"trace[152541990] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:10449; }","duration":"336.442617ms","start":"2026-02-19T03:12:57.56861Z","end":"2026-02-19T03:12:57.905052Z","steps":["trace[152541990] 'agreement among raft nodes before linearized reading' (duration: 335.918667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:12:57.904564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.814306ms","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-02-19T03:12:57.905135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:12:57.568589Z","time spent":"336.533409ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"info","ts":"2026-02-19T03:12:57.905166Z","caller":"traceutil/trace.go:171","msg":"trace[71051696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10449; }","duration":"204.469898ms","start":"2026-02-19T03:12:57.700674Z","end":"2026-02-19T03:12:57.905144Z","steps":["trace[71051696] 'agreement among raft nodes before linearized reading' (duration: 203.702013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:12:57.905164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.210892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:669"} {"level":"warn","ts":"2026-02-19T03:12:57.904619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.538758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T03:12:57.905245Z","caller":"traceutil/trace.go:171","msg":"trace[1557568218] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:10449; }","duration":"336.330115ms","start":"2026-02-19T03:12:57.568896Z","end":"2026-02-19T03:12:57.905226Z","steps":["trace[1557568218] 'agreement among raft nodes before linearized reading' (duration: 335.606171ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:12:57.905261Z","caller":"traceutil/trace.go:171","msg":"trace[1398483028] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:10449; }","duration":"346.20808ms","start":"2026-02-19T03:12:57.559035Z","end":"2026-02-19T03:12:57.905243Z","steps":["trace[1398483028] 'agreement among raft nodes before linearized reading' (duration: 345.561257ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:12:57.904991Z","caller":"traceutil/trace.go:171","msg":"trace[1977654909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10449; }","duration":"514.520932ms","start":"2026-02-19T03:12:57.390455Z","end":"2026-02-19T03:12:57.904976Z","steps":["trace[1977654909] 'agreement among raft nodes before linearized reading' (duration: 513.567313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:12:57.905296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:12:57.568876Z","time spent":"336.409837ms","remote":"127.0.0.1:55540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":692,"request content":"key:\"/registry/services/endpoints/openstack/cinder-api\" "} {"level":"warn","ts":"2026-02-19T03:12:57.90531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:12:57.559009Z","time spent":"346.285103ms","remote":"127.0.0.1:60740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-02-19T03:12:57.905327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T03:12:57.390434Z","time spent":"514.885839ms","remote":"127.0.0.1:50856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T03:13:22.515912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.897972ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T03:13:22.516136Z","caller":"traceutil/trace.go:171","msg":"trace[764176088] linearizableReadLoop","detail":"{readStateIndex:10955; appliedIndex:10954; }","duration":"147.850343ms","start":"2026-02-19T03:13:22.368265Z","end":"2026-02-19T03:13:22.516115Z","steps":["trace[764176088] 'read index received' (duration: 56.561µs)","trace[764176088] 'applied index is now lower than readState.Index' (duration: 147.792382ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:13:22.516278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.538009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T03:13:22.516331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.045257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8tcmh\" ","response":"range_response_count:1 size:6281"} {"level":"info","ts":"2026-02-19T03:13:22.516365Z","caller":"traceutil/trace.go:171","msg":"trace[260870393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10573; }","duration":"138.666071ms","start":"2026-02-19T03:13:22.377686Z","end":"2026-02-19T03:13:22.516352Z","steps":["trace[260870393] 'agreement among raft nodes before linearized reading' (duration: 138.545149ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:13:22.516424Z","caller":"traceutil/trace.go:171","msg":"trace[1859193026] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8tcmh; range_end:; response_count:1; response_revision:10573; }","duration":"148.197781ms","start":"2026-02-19T03:13:22.368205Z","end":"2026-02-19T03:13:22.516403Z","steps":["trace[1859193026] 'agreement among raft nodes before linearized reading' (duration: 147.993546ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:13:22.77189Z","caller":"traceutil/trace.go:171","msg":"trace[1105230800] transaction","detail":"{read_only:false; response_revision:10574; number_of_response:1; }","duration":"106.201787ms","start":"2026-02-19T03:13:22.665658Z","end":"2026-02-19T03:13:22.771859Z","steps":["trace[1105230800] 'process raft request' (duration: 105.959902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:13:23.048184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.853653ms","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-02-19T03:13:23.048333Z","caller":"traceutil/trace.go:171","msg":"trace[266735414] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:10575; }","duration":"148.021857ms","start":"2026-02-19T03:13:22.900289Z","end":"2026-02-19T03:13:23.048311Z","steps":["trace[266735414] 'range keys from in-memory index tree' (duration: 147.831493ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:13:23.314477Z","caller":"traceutil/trace.go:171","msg":"trace[952579211] transaction","detail":"{read_only:false; response_revision:10576; number_of_response:1; }","duration":"126.353408ms","start":"2026-02-19T03:13:23.188091Z","end":"2026-02-19T03:13:23.314444Z","steps":["trace[952579211] 'process raft request' (duration: 125.890919ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:13:23.54076Z","caller":"traceutil/trace.go:171","msg":"trace[1980033226] transaction","detail":"{read_only:false; response_revision:10578; number_of_response:1; }","duration":"104.622146ms","start":"2026-02-19T03:13:23.436105Z","end":"2026-02-19T03:13:23.540727Z","steps":["trace[1980033226] 'process raft request' (duration: 66.661653ms)","trace[1980033226] 'compare' (duration: 37.84471ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:13:24.244294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.708313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T03:13:24.244909Z","caller":"traceutil/trace.go:171","msg":"trace[1450705616] transaction","detail":"{read_only:false; response_revision:10582; number_of_response:1; }","duration":"197.054242ms","start":"2026-02-19T03:13:24.04782Z","end":"2026-02-19T03:13:24.244875Z","steps":["trace[1450705616] 'process raft request' (duration: 196.977509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:13:24.245197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.258595ms","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-02-19T03:13:24.245267Z","caller":"traceutil/trace.go:171","msg":"trace[193764929] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10582; }","duration":"198.409288ms","start":"2026-02-19T03:13:24.04684Z","end":"2026-02-19T03:13:24.245249Z","steps":["trace[193764929] 'agreement among raft nodes before linearized reading' (duration: 198.157783ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:13:24.244886Z","caller":"traceutil/trace.go:171","msg":"trace[319869840] transaction","detail":"{read_only:false; response_revision:10581; number_of_response:1; }","duration":"199.012699ms","start":"2026-02-19T03:13:24.045789Z","end":"2026-02-19T03:13:24.244802Z","steps":["trace[319869840] 'process raft request' (duration: 68.712474ms)","trace[319869840] 'compare' (duration: 129.476139ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T03:13:24.244938Z","caller":"traceutil/trace.go:171","msg":"trace[1832127795] linearizableReadLoop","detail":"{readStateIndex:10963; appliedIndex:10962; }","duration":"198.01149ms","start":"2026-02-19T03:13:24.04689Z","end":"2026-02-19T03:13:24.244901Z","steps":["trace[1832127795] 'read index received' (duration: 67.690623ms)","trace[1832127795] 'applied index is now lower than readState.Index' (duration: 130.317277ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:13:24.245746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.061821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T03:13:24.245785Z","caller":"traceutil/trace.go:171","msg":"trace[774740427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10582; }","duration":"196.130462ms","start":"2026-02-19T03:13:24.049645Z","end":"2026-02-19T03:13:24.245775Z","steps":["trace[774740427] 'agreement among raft nodes before linearized reading' (duration: 196.0341ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:17:13.837883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10015} {"level":"info","ts":"2026-02-19T03:17:13.920486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10015,"took":"80.011952ms","hash":2597540559,"current-db-size-bytes":40935424,"current-db-size":"41 MB","current-db-size-in-use-bytes":23240704,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-19T03:17:13.920565Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2597540559,"revision":10015,"compact-revision":7571} {"level":"info","ts":"2026-02-19T03:20:51.260945Z","caller":"traceutil/trace.go:171","msg":"trace[260076136] transaction","detail":"{read_only:false; response_revision:13250; number_of_response:1; }","duration":"297.800354ms","start":"2026-02-19T03:20:50.96312Z","end":"2026-02-19T03:20:51.26092Z","steps":["trace[260076136] 'process raft request' (duration: 297.669862ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:20:51.261376Z","caller":"traceutil/trace.go:171","msg":"trace[268148489] linearizableReadLoop","detail":"{readStateIndex:13736; appliedIndex:13736; }","duration":"293.489978ms","start":"2026-02-19T03:20:50.967873Z","end":"2026-02-19T03:20:51.261362Z","steps":["trace[268148489] 'read index received' (duration: 293.484188ms)","trace[268148489] 'applied index is now lower than readState.Index' (duration: 4.86µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T03:20:51.261593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.701783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-chunks-cache-0\" ","response":"range_response_count:1 size:4637"} {"level":"info","ts":"2026-02-19T03:20:51.261632Z","caller":"traceutil/trace.go:171","msg":"trace[1280744760] range","detail":"{range_begin:/registry/pods/monitoring/loki-chunks-cache-0; range_end:; response_count:1; response_revision:13250; }","duration":"293.804205ms","start":"2026-02-19T03:20:50.967817Z","end":"2026-02-19T03:20:51.261622Z","steps":["trace[1280744760] 'agreement among raft nodes before linearized reading' (duration: 293.628041ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:20:51.261924Z","caller":"traceutil/trace.go:171","msg":"trace[261373123] transaction","detail":"{read_only:false; response_revision:13251; number_of_response:1; }","duration":"130.727367ms","start":"2026-02-19T03:20:51.131137Z","end":"2026-02-19T03:20:51.261864Z","steps":["trace[261373123] 'process raft request' (duration: 130.446961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T03:20:51.262145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.413176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:5902"} {"level":"info","ts":"2026-02-19T03:20:51.262261Z","caller":"traceutil/trace.go:171","msg":"trace[50491349] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:13251; }","duration":"234.589569ms","start":"2026-02-19T03:20:51.027644Z","end":"2026-02-19T03:20:51.262233Z","steps":["trace[50491349] 'agreement among raft nodes before linearized reading' (duration: 234.12516ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T03:21:04.509984Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000035eb.wal"}