{"level":"warn","ts":"2026-09-09T01:05:05.717965Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T01:05:05.718091Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.71: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.71:2380","--initial-cluster=instance=https://162.253.55.71:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.71:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.71:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-09T01:05:05.718164Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T01:05:05.718174Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.71:2380"]} {"level":"info","ts":"2026-09-09T01:05:05.718194Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-09T01:05:05.718877Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"]} {"level":"info","ts":"2026-09-09T01:05:05.718963Z","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.71:2380"],"listen-peer-urls":["https://162.253.55.71:2380"],"advertise-client-urls":["https://162.253.55.71:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.71:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-09T01:05:05.721575Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.395343ms"} {"level":"info","ts":"2026-09-09T01:05:05.727519Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"54232768b5552f01","cluster-id":"66db39a47e9fb23b"} {"level":"info","ts":"2026-09-09T01:05:05.727639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T01:05:05.727674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became follower at term 0"} {"level":"info","ts":"2026-09-09T01:05:05.72771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 54232768b5552f01 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T01:05:05.727722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became follower at term 1"} {"level":"info","ts":"2026-09-09T01:05:05.727789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=(6062732854043160321)"} {"level":"warn","ts":"2026-09-09T01:05:05.73452Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T01:05:05.737307Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T01:05:05.738966Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-09T01:05:05.74194Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"54232768b5552f01","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T01:05:05.74218Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"54232768b5552f01","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T01:05:05.742365Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-09T01:05:05.74248Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-09T01:05:05.742496Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-09T01:05:05.743591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=(6062732854043160321)"} {"level":"info","ts":"2026-09-09T01:05:05.74378Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"66db39a47e9fb23b","local-member-id":"54232768b5552f01","added-peer-id":"54232768b5552f01","added-peer-peer-urls":["https://162.253.55.71:2380"]} {"level":"info","ts":"2026-09-09T01:05:05.75468Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-09T01:05:05.754821Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.71:2380"} {"level":"info","ts":"2026-09-09T01:05:05.754884Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.71:2380"} {"level":"info","ts":"2026-09-09T01:05:05.755087Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"54232768b5552f01","initial-advertise-peer-urls":["https://162.253.55.71:2380"],"listen-peer-urls":["https://162.253.55.71:2380"],"advertise-client-urls":["https://162.253.55.71:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T01:05:05.755135Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T01:05:05.828517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T01:05:05.828615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T01:05:05.828665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 received MsgPreVoteResp from 54232768b5552f01 at term 1"} {"level":"info","ts":"2026-09-09T01:05:05.82869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became candidate at term 2"} {"level":"info","ts":"2026-09-09T01:05:05.82871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 received MsgVoteResp from 54232768b5552f01 at term 2"} {"level":"info","ts":"2026-09-09T01:05:05.828728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became leader at term 2"} {"level":"info","ts":"2026-09-09T01:05:05.828939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 54232768b5552f01 elected leader 54232768b5552f01 at term 2"} {"level":"info","ts":"2026-09-09T01:05:05.830206Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:05:05.830712Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"54232768b5552f01","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.71:2379]}","request-path":"/0/members/54232768b5552f01/attributes","cluster-id":"66db39a47e9fb23b","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T01:05:05.83076Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:05:05.830728Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:05:05.83105Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T01:05:05.831166Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T01:05:05.831489Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"66db39a47e9fb23b","local-member-id":"54232768b5552f01","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:05:05.831626Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:05:05.831663Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:05:05.835066Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T01:05:05.835604Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.71:2379"} {"level":"info","ts":"2026-09-09T01:05:17.503531Z","caller":"traceutil/trace.go:171","msg":"trace[599106878] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"240.517233ms","start":"2026-09-09T01:05:17.262989Z","end":"2026-09-09T01:05:17.503506Z","steps":["trace[599106878] 'process raft request' (duration: 240.376439ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:05:17.768994Z","caller":"traceutil/trace.go:171","msg":"trace[2129315006] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"256.835659ms","start":"2026-09-09T01:05:17.511973Z","end":"2026-09-09T01:05:17.768809Z","steps":["trace[2129315006] 'process raft request' (duration: 256.657414ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:05:18.04555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.174606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:05:18.045788Z","caller":"traceutil/trace.go:171","msg":"trace[456495564] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"269.209455ms","start":"2026-09-09T01:05:17.776558Z","end":"2026-09-09T01:05:18.045768Z","steps":["trace[456495564] 'process raft request' (duration: 269.136233ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:05:18.045792Z","caller":"traceutil/trace.go:171","msg":"trace[802840691] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"284.353047ms","start":"2026-09-09T01:05:17.761381Z","end":"2026-09-09T01:05:18.045734Z","steps":["trace[802840691] 'process raft request' (duration: 54.040506ms)","trace[802840691] 'compare' (duration: 229.015492ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:05:18.045841Z","caller":"traceutil/trace.go:171","msg":"trace[752947552] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"277.015914ms","start":"2026-09-09T01:05:17.768775Z","end":"2026-09-09T01:05:18.045791Z","steps":["trace[752947552] 'read index received' (duration: 46.9359ms)","trace[752947552] 'applied index is now lower than readState.Index' (duration: 230.077604ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:05:18.046006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.418849ms","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-09-09T01:05:18.046066Z","caller":"traceutil/trace.go:171","msg":"trace[965058747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:397; }","duration":"351.546083ms","start":"2026-09-09T01:05:17.694507Z","end":"2026-09-09T01:05:18.046054Z","steps":["trace[965058747] 'agreement among raft nodes before linearized reading' (duration: 351.375128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:05:18.046112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:05:17.694481Z","time spent":"351.619295ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-09T01:05:50.225749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.679864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:05:50.225865Z","caller":"traceutil/trace.go:171","msg":"trace[1320444787] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:626; }","duration":"201.874321ms","start":"2026-09-09T01:05:50.023963Z","end":"2026-09-09T01:05:50.225838Z","steps":["trace[1320444787] 'range keys from in-memory index tree' (duration: 201.54763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:05:50.533828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.362914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:05:50.533933Z","caller":"traceutil/trace.go:171","msg":"trace[1509913203] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"198.434726ms","start":"2026-09-09T01:05:50.335482Z","end":"2026-09-09T01:05:50.533917Z","steps":["trace[1509913203] 'read index received' (duration: 24.821µs)","trace[1509913203] 'applied index is now lower than readState.Index' (duration: 198.408735ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:05:50.534147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.665202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4gzfb\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-09T01:05:50.534187Z","caller":"traceutil/trace.go:171","msg":"trace[1221660817] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4gzfb; range_end:; response_count:1; response_revision:629; }","duration":"198.741174ms","start":"2026-09-09T01:05:50.335435Z","end":"2026-09-09T01:05:50.534176Z","steps":["trace[1221660817] 'agreement among raft nodes before linearized reading' (duration: 198.528118ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:05:50.53419Z","caller":"traceutil/trace.go:171","msg":"trace[1705803203] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"216.591467ms","start":"2026-09-09T01:05:50.317575Z","end":"2026-09-09T01:05:50.534166Z","steps":["trace[1705803203] 'process raft request' (duration: 13.796619ms)","trace[1705803203] 'compare' (duration: 202.22022ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:05:51.347391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.223049ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:05:51.347555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.331475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:05:51.347603Z","caller":"traceutil/trace.go:171","msg":"trace[1448271984] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:629; }","duration":"360.448805ms","start":"2026-09-09T01:05:50.987123Z","end":"2026-09-09T01:05:51.347572Z","steps":["trace[1448271984] 'range keys from in-memory index tree' (duration: 360.198108ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:05:51.347648Z","caller":"traceutil/trace.go:171","msg":"trace[1691358168] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"795.627839ms","start":"2026-09-09T01:05:50.552002Z","end":"2026-09-09T01:05:51.34763Z","steps":["trace[1691358168] 'process raft request' (duration: 127.122139ms)","trace[1691358168] 'compare' (duration: 668.17637ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:05:51.347746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:05:50.551979Z","time spent":"795.727053ms","remote":"127.0.0.1:41090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:05:51.348907Z","caller":"traceutil/trace.go:171","msg":"trace[280670631] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"648.605665ms","start":"2026-09-09T01:05:50.700257Z","end":"2026-09-09T01:05:51.348863Z","steps":["trace[280670631] 'process raft request' (duration: 647.819321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:05:51.34915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:05:50.700215Z","time spent":"648.77981ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:05:51.349688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.653177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T01:05:51.349795Z","caller":"traceutil/trace.go:171","msg":"trace[1362760694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"485.833012ms","start":"2026-09-09T01:05:50.863941Z","end":"2026-09-09T01:05:51.349774Z","steps":["trace[1362760694] 'agreement among raft nodes before linearized reading' (duration: 485.510153ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:05:51.349874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:05:50.863909Z","time spent":"485.955006ms","remote":"127.0.0.1:41168","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-09-09T01:05:51.350293Z","caller":"traceutil/trace.go:171","msg":"trace[848071971] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"485.335678ms","start":"2026-09-09T01:05:50.864014Z","end":"2026-09-09T01:05:51.34935Z","steps":["trace[848071971] 'read index received' (duration: 483.786091ms)","trace[848071971] 'applied index is now lower than readState.Index' (duration: 1.547017ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:06:39.823442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.572894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:06:39.823626Z","caller":"traceutil/trace.go:171","msg":"trace[12995660] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1250; }","duration":"134.72721ms","start":"2026-09-09T01:06:39.688879Z","end":"2026-09-09T01:06:39.823606Z","steps":["trace[12995660] 'read index received' (duration: 44.420438ms)","trace[12995660] 'applied index is now lower than readState.Index' (duration: 90.305812ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:06:39.823698Z","caller":"traceutil/trace.go:171","msg":"trace[128910400] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"288.187372ms","start":"2026-09-09T01:06:39.535474Z","end":"2026-09-09T01:06:39.823662Z","steps":["trace[128910400] 'process raft request' (duration: 288.071659ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:06:39.823806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.920595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T01:06:39.823857Z","caller":"traceutil/trace.go:171","msg":"trace[355376253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1215; }","duration":"134.993258ms","start":"2026-09-09T01:06:39.688847Z","end":"2026-09-09T01:06:39.823841Z","steps":["trace[355376253] 'agreement among raft nodes before linearized reading' (duration: 134.842103ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:06:39.823951Z","caller":"traceutil/trace.go:171","msg":"trace[1303890554] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"395.538966ms","start":"2026-09-09T01:06:39.428257Z","end":"2026-09-09T01:06:39.823796Z","steps":["trace[1303890554] 'compare' (duration: 391.426749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:06:39.824102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:06:39.428236Z","time spent":"395.755512ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:07:08.65083Z","caller":"traceutil/trace.go:171","msg":"trace[1273735769] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"160.744884ms","start":"2026-09-09T01:07:08.490056Z","end":"2026-09-09T01:07:08.650801Z","steps":["trace[1273735769] 'process raft request' (duration: 113.546642ms)","trace[1273735769] 'compare' (duration: 46.890073ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:07:08.651274Z","caller":"traceutil/trace.go:171","msg":"trace[449858604] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"157.363043ms","start":"2026-09-09T01:07:08.493882Z","end":"2026-09-09T01:07:08.651245Z","steps":["trace[449858604] 'process raft request' (duration: 156.871727ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:07:08.651243Z","caller":"traceutil/trace.go:171","msg":"trace[718413704] linearizableReadLoop","detail":"{readStateIndex:1615; appliedIndex:1613; }","duration":"157.260079ms","start":"2026-09-09T01:07:08.493942Z","end":"2026-09-09T01:07:08.651202Z","steps":["trace[718413704] 'read index received' (duration: 109.680054ms)","trace[718413704] 'applied index is now lower than readState.Index' (duration: 47.578285ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:07:08.65138Z","caller":"traceutil/trace.go:171","msg":"trace[1599004744] transaction","detail":"{read_only:false; response_revision:1571; number_of_response:1; }","duration":"151.126881ms","start":"2026-09-09T01:07:08.500199Z","end":"2026-09-09T01:07:08.651325Z","steps":["trace[1599004744] 'process raft request' (duration: 150.906134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:07:08.651577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.59829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-09-09T01:07:08.651657Z","caller":"traceutil/trace.go:171","msg":"trace[1120270251] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:1571; }","duration":"157.748264ms","start":"2026-09-09T01:07:08.493887Z","end":"2026-09-09T01:07:08.651635Z","steps":["trace[1120270251] 'agreement among raft nodes before linearized reading' (duration: 157.450305ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:07:21.607548Z","caller":"traceutil/trace.go:171","msg":"trace[342389669] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"121.615079ms","start":"2026-09-09T01:07:21.485904Z","end":"2026-09-09T01:07:21.607519Z","steps":["trace[342389669] 'process raft request' (duration: 121.405123ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:07:31.044721Z","caller":"traceutil/trace.go:171","msg":"trace[1691740203] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"171.558946ms","start":"2026-09-09T01:07:30.87313Z","end":"2026-09-09T01:07:31.044689Z","steps":["trace[1691740203] 'process raft request' (duration: 111.554721ms)","trace[1691740203] 'compare' (duration: 59.691076ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:07:31.045066Z","caller":"traceutil/trace.go:171","msg":"trace[513542428] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"171.489305ms","start":"2026-09-09T01:07:30.873545Z","end":"2026-09-09T01:07:31.045035Z","steps":["trace[513542428] 'process raft request' (duration: 171.050061ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:07:31.045101Z","caller":"traceutil/trace.go:171","msg":"trace[64093723] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1978; }","duration":"121.489175ms","start":"2026-09-09T01:07:30.923554Z","end":"2026-09-09T01:07:31.045043Z","steps":["trace[64093723] 'read index received' (duration: 61.077277ms)","trace[64093723] 'applied index is now lower than readState.Index' (duration: 60.410568ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:07:31.045447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.850946ms","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-09-09T01:07:31.045536Z","caller":"traceutil/trace.go:171","msg":"trace[788130976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1909; }","duration":"122.00977ms","start":"2026-09-09T01:07:30.923504Z","end":"2026-09-09T01:07:31.045514Z","steps":["trace[788130976] 'agreement among raft nodes before linearized reading' (duration: 121.747753ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:07:31.58556Z","caller":"traceutil/trace.go:171","msg":"trace[1983238679] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"553.202568ms","start":"2026-09-09T01:07:31.032317Z","end":"2026-09-09T01:07:31.58552Z","steps":["trace[1983238679] 'process raft request' (duration: 459.670637ms)","trace[1983238679] 'compare' (duration: 93.370985ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:07:31.585751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:07:31.03228Z","time spent":"553.358154ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:07:31.624947Z","caller":"traceutil/trace.go:171","msg":"trace[1800077504] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"573.655063ms","start":"2026-09-09T01:07:31.05127Z","end":"2026-09-09T01:07:31.624925Z","steps":["trace[1800077504] 'process raft request' (duration: 573.504049ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:07:31.625063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:07:31.051237Z","time spent":"573.768877ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:07:31.625183Z","caller":"traceutil/trace.go:171","msg":"trace[148261276] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1981; }","duration":"435.413226ms","start":"2026-09-09T01:07:31.189751Z","end":"2026-09-09T01:07:31.625164Z","steps":["trace[148261276] 'read index received' (duration: 302.249633ms)","trace[148261276] 'applied index is now lower than readState.Index' (duration: 133.162462ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:07:31.625203Z","caller":"traceutil/trace.go:171","msg":"trace[1049036172] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"448.059582ms","start":"2026-09-09T01:07:31.177129Z","end":"2026-09-09T01:07:31.625188Z","steps":["trace[1049036172] 'process raft request' (duration: 447.746093ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:07:31.625307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:07:31.1771Z","time spent":"448.162785ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:07:31.625329Z","caller":"traceutil/trace.go:171","msg":"trace[1030301198] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"400.071676ms","start":"2026-09-09T01:07:31.225201Z","end":"2026-09-09T01:07:31.625273Z","steps":["trace[1030301198] 'process raft request' (duration: 399.861439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:07:31.625454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.694845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-09-09T01:07:31.62546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.643049ms","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-09-09T01:07:31.625504Z","caller":"traceutil/trace.go:171","msg":"trace[1262962482] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1913; }","duration":"435.791157ms","start":"2026-09-09T01:07:31.189697Z","end":"2026-09-09T01:07:31.625488Z","steps":["trace[1262962482] 'agreement among raft nodes before linearized reading' (duration: 435.593261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:07:31.625476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.222179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:07:31.625556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:07:31.189669Z","time spent":"435.87909ms","remote":"127.0.0.1:45554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-09T01:07:31.625565Z","caller":"traceutil/trace.go:171","msg":"trace[1579517002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1913; }","duration":"296.382883ms","start":"2026-09-09T01:07:31.329167Z","end":"2026-09-09T01:07:31.62555Z","steps":["trace[1579517002] 'agreement among raft nodes before linearized reading' (duration: 296.211908ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:07:31.625551Z","caller":"traceutil/trace.go:171","msg":"trace[864649813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1913; }","duration":"324.786122ms","start":"2026-09-09T01:07:31.300746Z","end":"2026-09-09T01:07:31.625532Z","steps":["trace[864649813] 'agreement among raft nodes before linearized reading' (duration: 324.583516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:07:31.6256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:07:31.300715Z","time spent":"324.875415ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T01:07:31.625597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:07:31.225181Z","time spent":"400.265321ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:07:31.98132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.642661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:07:31.981478Z","caller":"traceutil/trace.go:171","msg":"trace[1712800385] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1986; }","duration":"132.626325ms","start":"2026-09-09T01:07:31.848825Z","end":"2026-09-09T01:07:31.981451Z","steps":["trace[1712800385] 'read index received' (duration: 68.642µs)","trace[1712800385] 'applied index is now lower than readState.Index' (duration: 132.555693ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:07:31.981686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.862134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b73928c2-c81d-45ec-917d-1aea5beb1dcd\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-09-09T01:07:31.981727Z","caller":"traceutil/trace.go:171","msg":"trace[1533719134] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b73928c2-c81d-45ec-917d-1aea5beb1dcd; range_end:; response_count:1; response_revision:1915; }","duration":"132.961066ms","start":"2026-09-09T01:07:31.848751Z","end":"2026-09-09T01:07:31.981713Z","steps":["trace[1533719134] 'agreement among raft nodes before linearized reading' (duration: 132.770019ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:07:31.981898Z","caller":"traceutil/trace.go:171","msg":"trace[304010762] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"318.222273ms","start":"2026-09-09T01:07:31.663626Z","end":"2026-09-09T01:07:31.981848Z","steps":["trace[304010762] 'process raft request' (duration: 143.87129ms)","trace[304010762] 'compare' (duration: 173.229789ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:07:31.982134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:07:31.663549Z","time spent":"318.438289ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:07:36.28935Z","caller":"traceutil/trace.go:171","msg":"trace[1040261348] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"103.810785ms","start":"2026-09-09T01:07:36.185494Z","end":"2026-09-09T01:07:36.289305Z","steps":["trace[1040261348] 'process raft request' (duration: 100.218115ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:22.044572Z","caller":"traceutil/trace.go:171","msg":"trace[145041635] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"123.037193ms","start":"2026-09-09T01:08:21.921483Z","end":"2026-09-09T01:08:22.04452Z","steps":["trace[145041635] 'process raft request' (duration: 91.923762ms)","trace[145041635] 'compare' (duration: 30.936125ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:08:27.802096Z","caller":"traceutil/trace.go:171","msg":"trace[234995454] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"229.088347ms","start":"2026-09-09T01:08:27.572963Z","end":"2026-09-09T01:08:27.802051Z","steps":["trace[234995454] 'process raft request' (duration: 228.834639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:08:28.129977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.224993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:08:28.130367Z","caller":"traceutil/trace.go:171","msg":"trace[2099997793] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"230.815057ms","start":"2026-09-09T01:08:27.899475Z","end":"2026-09-09T01:08:28.13029Z","steps":["trace[2099997793] 'process raft request' (duration: 31.817842ms)","trace[2099997793] 'compare' (duration: 197.981815ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:08:28.248579Z","caller":"traceutil/trace.go:171","msg":"trace[1523499544] linearizableReadLoop","detail":"{readStateIndex:2350; appliedIndex:2348; }","duration":"101.623338ms","start":"2026-09-09T01:08:28.146917Z","end":"2026-09-09T01:08:28.24854Z","steps":["trace[1523499544] 'read index received' (duration: 87.863287ms)","trace[1523499544] 'applied index is now lower than readState.Index' (duration: 13.758821ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:08:28.248727Z","caller":"traceutil/trace.go:171","msg":"trace[63254523] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"112.097168ms","start":"2026-09-09T01:08:28.136599Z","end":"2026-09-09T01:08:28.248696Z","steps":["trace[63254523] 'process raft request' (duration: 98.162892ms)","trace[63254523] 'compare' (duration: 13.495493ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:08:28.24882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.864625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:08:28.248801Z","caller":"traceutil/trace.go:171","msg":"trace[280525048] transaction","detail":"{read_only:false; response_revision:2265; number_of_response:1; }","duration":"111.350135ms","start":"2026-09-09T01:08:28.137409Z","end":"2026-09-09T01:08:28.248759Z","steps":["trace[280525048] 'process raft request' (duration: 111.054206ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:28.24887Z","caller":"traceutil/trace.go:171","msg":"trace[918836001] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2265; }","duration":"101.953708ms","start":"2026-09-09T01:08:28.146899Z","end":"2026-09-09T01:08:28.248853Z","steps":["trace[918836001] 'agreement among raft nodes before linearized reading' (duration: 101.774142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:56.850084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.669626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-09T01:09:56.85023Z","caller":"traceutil/trace.go:171","msg":"trace[666332317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2917; }","duration":"135.872872ms","start":"2026-09-09T01:09:56.714328Z","end":"2026-09-09T01:09:56.850201Z","steps":["trace[666332317] 'range keys from in-memory index tree' (duration: 135.372977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:56.850311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.390732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T01:09:56.850695Z","caller":"traceutil/trace.go:171","msg":"trace[1891501894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2917; }","duration":"122.774643ms","start":"2026-09-09T01:09:56.727901Z","end":"2026-09-09T01:09:56.850676Z","steps":["trace[1891501894] 'range keys from in-memory index tree' (duration: 122.254738ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:09.51048Z","caller":"traceutil/trace.go:171","msg":"trace[2072390260] transaction","detail":"{read_only:false; response_revision:2997; number_of_response:1; }","duration":"206.962064ms","start":"2026-09-09T01:10:09.303478Z","end":"2026-09-09T01:10:09.51044Z","steps":["trace[2072390260] 'process raft request' (duration: 206.674916ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:09.882147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.835127ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-09-09T01:10:09.882332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:09.511843Z","time spent":"370.437937ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-09T01:10:09.963532Z","caller":"traceutil/trace.go:171","msg":"trace[1149259658] transaction","detail":"{read_only:false; response_revision:2998; number_of_response:1; }","duration":"321.618691ms","start":"2026-09-09T01:10:09.641887Z","end":"2026-09-09T01:10:09.963506Z","steps":["trace[1149259658] 'process raft request' (duration: 321.410305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:09.963704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:09.641851Z","time spent":"321.753145ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:10:09.963713Z","caller":"traceutil/trace.go:171","msg":"trace[1610785902] transaction","detail":"{read_only:false; response_revision:2999; number_of_response:1; }","duration":"316.036471ms","start":"2026-09-09T01:10:09.647631Z","end":"2026-09-09T01:10:09.963668Z","steps":["trace[1610785902] 'process raft request' (duration: 315.830235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:09.963848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:09.647604Z","time spent":"316.186456ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T01:10:10.510552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.112441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:10:10.510648Z","caller":"traceutil/trace.go:171","msg":"trace[916662641] linearizableReadLoop","detail":"{readStateIndex:3113; appliedIndex:3112; }","duration":"398.076196ms","start":"2026-09-09T01:10:10.112555Z","end":"2026-09-09T01:10:10.510631Z","steps":["trace[916662641] 'read index received' (duration: 62.724868ms)","trace[916662641] 'applied index is now lower than readState.Index' (duration: 335.348818ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:10.510722Z","caller":"traceutil/trace.go:171","msg":"trace[1898398632] transaction","detail":"{read_only:false; response_revision:3000; number_of_response:1; }","duration":"626.355119ms","start":"2026-09-09T01:10:09.884357Z","end":"2026-09-09T01:10:10.510712Z","steps":["trace[1898398632] 'process raft request' (duration: 290.98986ms)","trace[1898398632] 'compare' (duration: 334.943026ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:10:10.510765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:09.884335Z","time spent":"626.406599ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:10:10.511021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.463019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:10:10.511071Z","caller":"traceutil/trace.go:171","msg":"trace[937299934] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3000; }","duration":"398.517991ms","start":"2026-09-09T01:10:10.112538Z","end":"2026-09-09T01:10:10.511056Z","steps":["trace[937299934] 'agreement among raft nodes before linearized reading' (duration: 398.423418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:10.51111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:10.112491Z","time spent":"398.607413ms","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-09T01:10:10.511112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.204635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-09-09T01:10:10.511187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.726576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-09T01:10:10.511223Z","caller":"traceutil/trace.go:171","msg":"trace[527202009] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3000; }","duration":"166.348589ms","start":"2026-09-09T01:10:10.344853Z","end":"2026-09-09T01:10:10.511202Z","steps":["trace[527202009] 'agreement among raft nodes before linearized reading' (duration: 166.090301ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:10.511283Z","caller":"traceutil/trace.go:171","msg":"trace[484147500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3000; }","duration":"179.876711ms","start":"2026-09-09T01:10:10.331383Z","end":"2026-09-09T01:10:10.51126Z","steps":["trace[484147500] 'agreement among raft nodes before linearized reading' (duration: 179.621693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:10.511181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.806739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:10:10.511356Z","caller":"traceutil/trace.go:171","msg":"trace[877259529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3000; }","duration":"181.056056ms","start":"2026-09-09T01:10:10.330275Z","end":"2026-09-09T01:10:10.511331Z","steps":["trace[877259529] 'agreement among raft nodes before linearized reading' (duration: 180.769617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:10.511652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.256662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:10:10.511747Z","caller":"traceutil/trace.go:171","msg":"trace[17831345] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3000; }","duration":"254.403406ms","start":"2026-09-09T01:10:10.257331Z","end":"2026-09-09T01:10:10.511734Z","steps":["trace[17831345] 'agreement among raft nodes before linearized reading' (duration: 253.412985ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:11.023494Z","caller":"traceutil/trace.go:171","msg":"trace[664170771] linearizableReadLoop","detail":"{readStateIndex:3114; appliedIndex:3113; }","duration":"506.969477ms","start":"2026-09-09T01:10:10.516505Z","end":"2026-09-09T01:10:11.023474Z","steps":["trace[664170771] 'read index received' (duration: 485.471023ms)","trace[664170771] 'applied index is now lower than readState.Index' (duration: 21.497324ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:11.023571Z","caller":"traceutil/trace.go:171","msg":"trace[1005693782] transaction","detail":"{read_only:false; response_revision:3001; number_of_response:1; }","duration":"508.320268ms","start":"2026-09-09T01:10:10.515187Z","end":"2026-09-09T01:10:11.023507Z","steps":["trace[1005693782] 'process raft request' (duration: 486.775143ms)","trace[1005693782] 'compare' (duration: 20.742601ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:10:11.023762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:10.515175Z","time spent":"508.481354ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:10:11.023874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.346958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T01:10:11.023921Z","caller":"traceutil/trace.go:171","msg":"trace[944762786] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:3001; }","duration":"507.41133ms","start":"2026-09-09T01:10:10.516498Z","end":"2026-09-09T01:10:11.02391Z","steps":["trace[944762786] 'agreement among raft nodes before linearized reading' (duration: 507.07538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:11.023953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:10.516463Z","time spent":"507.481442ms","remote":"127.0.0.1:41176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-09-09T01:10:11.031288Z","caller":"traceutil/trace.go:171","msg":"trace[1918519921] transaction","detail":"{read_only:false; response_revision:3002; number_of_response:1; }","duration":"508.497484ms","start":"2026-09-09T01:10:10.522768Z","end":"2026-09-09T01:10:11.031266Z","steps":["trace[1918519921] 'process raft request' (duration: 508.316029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:11.031463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:10.522564Z","time spent":"508.821284ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:10:11.031557Z","caller":"traceutil/trace.go:171","msg":"trace[1922775155] transaction","detail":"{read_only:false; response_revision:3003; number_of_response:1; }","duration":"505.062199ms","start":"2026-09-09T01:10:10.526442Z","end":"2026-09-09T01:10:11.031504Z","steps":["trace[1922775155] 'process raft request' (duration: 504.76744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:11.031622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.164247ms","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-09-09T01:10:11.031678Z","caller":"traceutil/trace.go:171","msg":"trace[1749908929] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3004; }","duration":"514.24328ms","start":"2026-09-09T01:10:10.517421Z","end":"2026-09-09T01:10:11.031664Z","steps":["trace[1749908929] 'agreement among raft nodes before linearized reading' (duration: 514.059874ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:11.031685Z","caller":"traceutil/trace.go:171","msg":"trace[145866337] transaction","detail":"{read_only:false; response_revision:3004; number_of_response:1; }","duration":"110.522241ms","start":"2026-09-09T01:10:10.921143Z","end":"2026-09-09T01:10:11.031665Z","steps":["trace[145866337] 'process raft request' (duration: 110.237732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:11.031711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:10.517408Z","time spent":"514.295001ms","remote":"127.0.0.1:41092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1335,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-09-09T01:10:11.031804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:10.526418Z","time spent":"505.254856ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:10:11.031877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.725208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:10:11.031952Z","caller":"traceutil/trace.go:171","msg":"trace[513561352] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:3004; }","duration":"352.852481ms","start":"2026-09-09T01:10:10.679077Z","end":"2026-09-09T01:10:11.03193Z","steps":["trace[513561352] 'agreement among raft nodes before linearized reading' (duration: 352.754918ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:11.032004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:10.679041Z","time spent":"352.947964ms","remote":"127.0.0.1:35370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-09-09T01:10:16.447744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.787772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:10:16.447831Z","caller":"traceutil/trace.go:171","msg":"trace[474990724] linearizableReadLoop","detail":"{readStateIndex:3146; appliedIndex:3145; }","duration":"101.60509ms","start":"2026-09-09T01:10:16.346211Z","end":"2026-09-09T01:10:16.447817Z","steps":["trace[474990724] 'read index received' (duration: 50.592µs)","trace[474990724] 'applied index is now lower than readState.Index' (duration: 101.553438ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:10:16.447967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.758515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-09T01:10:16.447995Z","caller":"traceutil/trace.go:171","msg":"trace[1029932085] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3032; }","duration":"101.851017ms","start":"2026-09-09T01:10:16.346135Z","end":"2026-09-09T01:10:16.447986Z","steps":["trace[1029932085] 'agreement among raft nodes before linearized reading' (duration: 101.719473ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:16.448087Z","caller":"traceutil/trace.go:171","msg":"trace[168763122] transaction","detail":"{read_only:false; response_revision:3032; number_of_response:1; }","duration":"285.154872ms","start":"2026-09-09T01:10:16.162863Z","end":"2026-09-09T01:10:16.448018Z","steps":["trace[168763122] 'process raft request' (duration: 96.019951ms)","trace[168763122] 'compare' (duration: 188.699628ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:33.592061Z","caller":"traceutil/trace.go:171","msg":"trace[2032518997] transaction","detail":"{read_only:false; response_revision:3122; number_of_response:1; }","duration":"339.041931ms","start":"2026-09-09T01:10:33.252985Z","end":"2026-09-09T01:10:33.592027Z","steps":["trace[2032518997] 'process raft request' (duration: 338.849315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:33.592271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:33.252955Z","time spent":"339.190655ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:10:33.593823Z","caller":"traceutil/trace.go:171","msg":"trace[316521067] linearizableReadLoop","detail":"{readStateIndex:3241; appliedIndex:3238; }","duration":"264.364449ms","start":"2026-09-09T01:10:33.329444Z","end":"2026-09-09T01:10:33.593808Z","steps":["trace[316521067] 'read index received' (duration: 262.447211ms)","trace[316521067] 'applied index is now lower than readState.Index' (duration: 1.915718ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:33.59406Z","caller":"traceutil/trace.go:171","msg":"trace[357875762] transaction","detail":"{read_only:false; response_revision:3123; number_of_response:1; }","duration":"339.63209ms","start":"2026-09-09T01:10:33.254418Z","end":"2026-09-09T01:10:33.59405Z","steps":["trace[357875762] 'process raft request' (duration: 339.29775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:33.594108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:33.254405Z","time spent":"339.675661ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:10:33.594276Z","caller":"traceutil/trace.go:171","msg":"trace[723085116] transaction","detail":"{read_only:false; response_revision:3124; number_of_response:1; }","duration":"338.119793ms","start":"2026-09-09T01:10:33.256146Z","end":"2026-09-09T01:10:33.594266Z","steps":["trace[723085116] 'process raft request' (duration: 337.627838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:33.59432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:10:33.256132Z","time spent":"338.164345ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:10:33.594468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.020359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:10:33.594503Z","caller":"traceutil/trace.go:171","msg":"trace[369280676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3124; }","duration":"265.085441ms","start":"2026-09-09T01:10:33.329408Z","end":"2026-09-09T01:10:33.594494Z","steps":["trace[369280676] 'agreement among raft nodes before linearized reading' (duration: 265.020839ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:33.59468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.877326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-09T01:10:33.594696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.451189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T01:10:33.594768Z","caller":"traceutil/trace.go:171","msg":"trace[713115136] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3124; }","duration":"241.989539ms","start":"2026-09-09T01:10:33.352759Z","end":"2026-09-09T01:10:33.594749Z","steps":["trace[713115136] 'agreement among raft nodes before linearized reading' (duration: 241.756002ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:33.594778Z","caller":"traceutil/trace.go:171","msg":"trace[1563452928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3124; }","duration":"234.551573ms","start":"2026-09-09T01:10:33.360209Z","end":"2026-09-09T01:10:33.59476Z","steps":["trace[1563452928] 'agreement among raft nodes before linearized reading' (duration: 234.308315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:33.594758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.998608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-09T01:10:33.594839Z","caller":"traceutil/trace.go:171","msg":"trace[775916794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3124; }","duration":"215.145282ms","start":"2026-09-09T01:10:33.379674Z","end":"2026-09-09T01:10:33.594819Z","steps":["trace[775916794] 'agreement among raft nodes before linearized reading' (duration: 214.876074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:33.595501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.219524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-09T01:10:33.59555Z","caller":"traceutil/trace.go:171","msg":"trace[1354386937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3124; }","duration":"236.280906ms","start":"2026-09-09T01:10:33.359258Z","end":"2026-09-09T01:10:33.595538Z","steps":["trace[1354386937] 'agreement among raft nodes before linearized reading' (duration: 235.407709ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:13:31.667476Z","caller":"traceutil/trace.go:171","msg":"trace[999234253] transaction","detail":"{read_only:false; response_revision:4263; number_of_response:1; }","duration":"112.26335ms","start":"2026-09-09T01:13:31.55517Z","end":"2026-09-09T01:13:31.667434Z","steps":["trace[999234253] 'process raft request' (duration: 111.97483ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:06.16343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2978} {"level":"info","ts":"2026-09-09T01:15:06.249747Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2978,"took":"84.908515ms","hash":1073404683,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":28725248,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-09T01:15:06.249807Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1073404683,"revision":2978,"compact-revision":-1} {"level":"info","ts":"2026-09-09T01:15:07.548397Z","caller":"traceutil/trace.go:171","msg":"trace[366063408] linearizableReadLoop","detail":"{readStateIndex:5564; appliedIndex:5563; }","duration":"101.207241ms","start":"2026-09-09T01:15:07.447154Z","end":"2026-09-09T01:15:07.548362Z","steps":["trace[366063408] 'read index received' (duration: 100.489749ms)","trace[366063408] 'applied index is now lower than readState.Index' (duration: 716.172µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:15:07.54946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.252693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:15:07.549549Z","caller":"traceutil/trace.go:171","msg":"trace[193142109] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5360; }","duration":"102.420918ms","start":"2026-09-09T01:15:07.447114Z","end":"2026-09-09T01:15:07.549535Z","steps":["trace[193142109] 'agreement among raft nodes before linearized reading' (duration: 102.166121ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:07.549437Z","caller":"traceutil/trace.go:171","msg":"trace[1292953074] transaction","detail":"{read_only:false; response_revision:5360; number_of_response:1; }","duration":"102.420818ms","start":"2026-09-09T01:15:07.446965Z","end":"2026-09-09T01:15:07.549385Z","steps":["trace[1292953074] 'process raft request' (duration: 100.790608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:15:08.423504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.0326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cz5q7\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T01:15:08.423613Z","caller":"traceutil/trace.go:171","msg":"trace[466512367] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cz5q7; range_end:; response_count:1; response_revision:5374; }","duration":"109.211795ms","start":"2026-09-09T01:15:08.314375Z","end":"2026-09-09T01:15:08.423587Z","steps":["trace[466512367] 'range keys from in-memory index tree' (duration: 108.792173ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:08.455951Z","caller":"traceutil/trace.go:171","msg":"trace[1915393444] linearizableReadLoop","detail":"{readStateIndex:5580; appliedIndex:5579; }","duration":"126.934844ms","start":"2026-09-09T01:15:08.328971Z","end":"2026-09-09T01:15:08.455905Z","steps":["trace[1915393444] 'read index received' (duration: 126.714828ms)","trace[1915393444] 'applied index is now lower than readState.Index' (duration: 217.936µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:15:08.456544Z","caller":"traceutil/trace.go:171","msg":"trace[1260278580] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"135.00095ms","start":"2026-09-09T01:15:08.321521Z","end":"2026-09-09T01:15:08.456522Z","steps":["trace[1260278580] 'process raft request' (duration: 134.107893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:15:08.456998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.996437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:15:08.457059Z","caller":"traceutil/trace.go:171","msg":"trace[76402582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5375; }","duration":"128.11292ms","start":"2026-09-09T01:15:08.328928Z","end":"2026-09-09T01:15:08.457041Z","steps":["trace[76402582] 'agreement among raft nodes before linearized reading' (duration: 127.934624ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:08.45855Z","caller":"traceutil/trace.go:171","msg":"trace[170432424] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"117.032113ms","start":"2026-09-09T01:15:08.341491Z","end":"2026-09-09T01:15:08.458523Z","steps":["trace[170432424] 'process raft request' (duration: 116.377823ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:25.521371Z","caller":"traceutil/trace.go:171","msg":"trace[2104788056] transaction","detail":"{read_only:false; response_revision:5772; number_of_response:1; }","duration":"103.605834ms","start":"2026-09-09T01:15:25.417726Z","end":"2026-09-09T01:15:25.521332Z","steps":["trace[2104788056] 'process raft request' (duration: 103.318195ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:25.521751Z","caller":"traceutil/trace.go:171","msg":"trace[53977964] transaction","detail":"{read_only:false; response_revision:5773; number_of_response:1; }","duration":"103.722067ms","start":"2026-09-09T01:15:25.417997Z","end":"2026-09-09T01:15:25.521719Z","steps":["trace[53977964] 'process raft request' (duration: 103.284284ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:25.522334Z","caller":"traceutil/trace.go:171","msg":"trace[1588687164] transaction","detail":"{read_only:false; response_revision:5774; number_of_response:1; }","duration":"104.142351ms","start":"2026-09-09T01:15:25.418158Z","end":"2026-09-09T01:15:25.5223Z","steps":["trace[1588687164] 'process raft request' (duration: 103.978116ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:25.522691Z","caller":"traceutil/trace.go:171","msg":"trace[2144634565] transaction","detail":"{read_only:false; response_revision:5775; number_of_response:1; }","duration":"104.111379ms","start":"2026-09-09T01:15:25.41857Z","end":"2026-09-09T01:15:25.522682Z","steps":["trace[2144634565] 'process raft request' (duration: 103.682146ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:25.522714Z","caller":"traceutil/trace.go:171","msg":"trace[1579363743] transaction","detail":"{read_only:false; response_revision:5776; number_of_response:1; }","duration":"103.857022ms","start":"2026-09-09T01:15:25.418816Z","end":"2026-09-09T01:15:25.522673Z","steps":["trace[1579363743] 'process raft request' (duration: 103.729198ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:25.860096Z","caller":"traceutil/trace.go:171","msg":"trace[1756302084] transaction","detail":"{read_only:false; response_revision:5778; number_of_response:1; }","duration":"270.768303ms","start":"2026-09-09T01:15:25.589294Z","end":"2026-09-09T01:15:25.860063Z","steps":["trace[1756302084] 'process raft request' (duration: 270.525546ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:25.875636Z","caller":"traceutil/trace.go:171","msg":"trace[1086102602] transaction","detail":"{read_only:false; response_revision:5780; number_of_response:1; }","duration":"282.710977ms","start":"2026-09-09T01:15:25.592907Z","end":"2026-09-09T01:15:25.875618Z","steps":["trace[1086102602] 'process raft request' (duration: 282.653785ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:25.875641Z","caller":"traceutil/trace.go:171","msg":"trace[1131446134] transaction","detail":"{read_only:false; response_revision:5779; number_of_response:1; }","duration":"284.062278ms","start":"2026-09-09T01:15:25.59156Z","end":"2026-09-09T01:15:25.875622Z","steps":["trace[1131446134] 'process raft request' (duration: 283.887113ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:35.565067Z","caller":"traceutil/trace.go:171","msg":"trace[45195041] transaction","detail":"{read_only:false; response_revision:5957; number_of_response:1; }","duration":"124.383715ms","start":"2026-09-09T01:15:35.440649Z","end":"2026-09-09T01:15:35.565033Z","steps":["trace[45195041] 'process raft request' (duration: 60.895753ms)","trace[45195041] 'compare' (duration: 63.230485ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:15:35.878985Z","caller":"traceutil/trace.go:171","msg":"trace[1069401726] transaction","detail":"{read_only:false; response_revision:5958; number_of_response:1; }","duration":"308.924625ms","start":"2026-09-09T01:15:35.570007Z","end":"2026-09-09T01:15:35.878931Z","steps":["trace[1069401726] 'process raft request' (duration: 266.789361ms)","trace[1069401726] 'compare' (duration: 41.668459ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:15:35.879273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:15:35.569988Z","time spent":"309.10361ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:15:35.959746Z","caller":"traceutil/trace.go:171","msg":"trace[310853420] linearizableReadLoop","detail":"{readStateIndex:6180; appliedIndex:6178; }","duration":"365.228968ms","start":"2026-09-09T01:15:35.594476Z","end":"2026-09-09T01:15:35.959704Z","steps":["trace[310853420] 'read index received' (duration: 242.355227ms)","trace[310853420] 'applied index is now lower than readState.Index' (duration: 122.872351ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:15:35.959808Z","caller":"traceutil/trace.go:171","msg":"trace[981906123] transaction","detail":"{read_only:false; response_revision:5959; number_of_response:1; }","duration":"384.976739ms","start":"2026-09-09T01:15:35.574797Z","end":"2026-09-09T01:15:35.959774Z","steps":["trace[981906123] 'process raft request' (duration: 384.687751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:15:35.960035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.535128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-09T01:15:35.960197Z","caller":"traceutil/trace.go:171","msg":"trace[276260001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5959; }","duration":"365.741975ms","start":"2026-09-09T01:15:35.594444Z","end":"2026-09-09T01:15:35.960186Z","steps":["trace[276260001] 'agreement among raft nodes before linearized reading' (duration: 365.435165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:15:35.960229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:15:35.594427Z","time spent":"365.793957ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-09T01:15:35.960371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:15:35.574762Z","time spent":"385.344471ms","remote":"127.0.0.1:41090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:15:36.137266Z","caller":"traceutil/trace.go:171","msg":"trace[1867365420] linearizableReadLoop","detail":"{readStateIndex:6181; appliedIndex:6180; }","duration":"177.216755ms","start":"2026-09-09T01:15:35.959962Z","end":"2026-09-09T01:15:36.137178Z","steps":["trace[1867365420] 'read index received' (duration: 160.540108ms)","trace[1867365420] 'applied index is now lower than readState.Index' (duration: 16.673717ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:15:36.137582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.952937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T01:15:36.137621Z","caller":"traceutil/trace.go:171","msg":"trace[882758289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5960; }","duration":"541.02667ms","start":"2026-09-09T01:15:35.596581Z","end":"2026-09-09T01:15:36.137608Z","steps":["trace[882758289] 'agreement among raft nodes before linearized reading' (duration: 540.791053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:15:36.137663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:15:35.596574Z","time spent":"541.077551ms","remote":"127.0.0.1:41168","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-09-09T01:15:36.137699Z","caller":"traceutil/trace.go:171","msg":"trace[222092881] transaction","detail":"{read_only:false; response_revision:5960; number_of_response:1; }","duration":"203.517745ms","start":"2026-09-09T01:15:35.934107Z","end":"2026-09-09T01:15:36.137625Z","steps":["trace[222092881] 'process raft request' (duration: 186.392634ms)","trace[222092881] 'compare' (duration: 16.158432ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:15:36.137927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.766155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:15:36.138023Z","caller":"traceutil/trace.go:171","msg":"trace[1592208720] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:5960; }","duration":"295.962481ms","start":"2026-09-09T01:15:35.842036Z","end":"2026-09-09T01:15:36.137999Z","steps":["trace[1592208720] 'agreement among raft nodes before linearized reading' (duration: 295.601949ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:15:36.143002Z","caller":"traceutil/trace.go:171","msg":"trace[731771153] transaction","detail":"{read_only:false; response_revision:5961; number_of_response:1; }","duration":"176.297107ms","start":"2026-09-09T01:15:35.966682Z","end":"2026-09-09T01:15:36.142979Z","steps":["trace[731771153] 'process raft request' (duration: 176.133592ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:20:06.171054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5304} {"level":"info","ts":"2026-09-09T01:20:06.253085Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5304,"took":"80.417718ms","hash":2454085851,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":28405760,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T01:20:06.25316Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2454085851,"revision":5304,"compact-revision":2978} {"level":"info","ts":"2026-09-09T01:21:59.163997Z","caller":"traceutil/trace.go:171","msg":"trace[2031166950] transaction","detail":"{read_only:false; response_revision:8866; number_of_response:1; }","duration":"142.855836ms","start":"2026-09-09T01:21:59.021096Z","end":"2026-09-09T01:21:59.163952Z","steps":["trace[2031166950] 'process raft request' (duration: 96.372919ms)","trace[2031166950] 'compare' (duration: 46.202868ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:22:00.723958Z","caller":"traceutil/trace.go:171","msg":"trace[1554686359] linearizableReadLoop","detail":"{readStateIndex:9185; appliedIndex:9184; }","duration":"467.435642ms","start":"2026-09-09T01:22:00.25648Z","end":"2026-09-09T01:22:00.723916Z","steps":["trace[1554686359] 'read index received' (duration: 467.190044ms)","trace[1554686359] 'applied index is now lower than readState.Index' (duration: 244.538µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:22:00.724565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.073191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T01:22:00.724615Z","caller":"traceutil/trace.go:171","msg":"trace[629100332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8871; }","duration":"468.203706ms","start":"2026-09-09T01:22:00.256399Z","end":"2026-09-09T01:22:00.724602Z","steps":["trace[629100332] 'agreement among raft nodes before linearized reading' (duration: 467.996319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:00.724651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:22:00.25637Z","time spent":"468.271068ms","remote":"127.0.0.1:41168","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-09-09T01:22:00.724773Z","caller":"traceutil/trace.go:171","msg":"trace[136845536] transaction","detail":"{read_only:false; response_revision:8871; number_of_response:1; }","duration":"485.598266ms","start":"2026-09-09T01:22:00.239165Z","end":"2026-09-09T01:22:00.724763Z","steps":["trace[136845536] 'process raft request' (duration: 484.546794ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:00.724871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:22:00.239109Z","time spent":"485.687658ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:22:00.978009Z","caller":"traceutil/trace.go:171","msg":"trace[906236256] transaction","detail":"{read_only:false; response_revision:8872; number_of_response:1; }","duration":"594.114285ms","start":"2026-09-09T01:22:00.383851Z","end":"2026-09-09T01:22:00.977965Z","steps":["trace[906236256] 'process raft request' (duration: 590.936818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:00.978268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:22:00.383815Z","time spent":"594.29777ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:22:00.978465Z","caller":"traceutil/trace.go:171","msg":"trace[1197805236] transaction","detail":"{read_only:false; response_revision:8873; number_of_response:1; }","duration":"592.99692ms","start":"2026-09-09T01:22:00.385444Z","end":"2026-09-09T01:22:00.978441Z","steps":["trace[1197805236] 'process raft request' (duration: 592.463994ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:22:00.978517Z","caller":"traceutil/trace.go:171","msg":"trace[138202143] linearizableReadLoop","detail":"{readStateIndex:9187; appliedIndex:9185; }","duration":"254.449698ms","start":"2026-09-09T01:22:00.724047Z","end":"2026-09-09T01:22:00.978497Z","steps":["trace[138202143] 'read index received' (duration: 250.761706ms)","trace[138202143] 'applied index is now lower than readState.Index' (duration: 3.685702ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:22:00.978575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:22:00.385402Z","time spent":"593.119384ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:22:00.97856Z","caller":"traceutil/trace.go:171","msg":"trace[507428481] transaction","detail":"{read_only:false; response_revision:8874; number_of_response:1; }","duration":"248.462885ms","start":"2026-09-09T01:22:00.730059Z","end":"2026-09-09T01:22:00.978522Z","steps":["trace[507428481] 'process raft request' (duration: 248.259799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:00.978773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.521367ms","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-09-09T01:22:00.978841Z","caller":"traceutil/trace.go:171","msg":"trace[1875643150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8874; }","duration":"716.6408ms","start":"2026-09-09T01:22:00.262182Z","end":"2026-09-09T01:22:00.978823Z","steps":["trace[1875643150] 'agreement among raft nodes before linearized reading' (duration: 716.377772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:00.978804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.395291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:22:00.978889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:22:00.262157Z","time spent":"716.720203ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-09T01:22:00.978929Z","caller":"traceutil/trace.go:171","msg":"trace[2101213847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8874; }","duration":"649.593607ms","start":"2026-09-09T01:22:00.329305Z","end":"2026-09-09T01:22:00.978899Z","steps":["trace[2101213847] 'agreement among raft nodes before linearized reading' (duration: 649.39139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:00.979001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:22:00.32928Z","time spent":"649.71245ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-09T01:22:02.032747Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023eb.wal"} {"level":"info","ts":"2026-09-09T01:22:13.600942Z","caller":"traceutil/trace.go:171","msg":"trace[248259515] transaction","detail":"{read_only:false; response_revision:8944; number_of_response:1; }","duration":"194.361576ms","start":"2026-09-09T01:22:13.406549Z","end":"2026-09-09T01:22:13.600911Z","steps":["trace[248259515] 'process raft request' (duration: 157.10401ms)","trace[248259515] 'compare' (duration: 37.06495ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:22:13.600938Z","caller":"traceutil/trace.go:171","msg":"trace[833998330] linearizableReadLoop","detail":"{readStateIndex:9260; appliedIndex:9259; }","duration":"133.307074ms","start":"2026-09-09T01:22:13.467588Z","end":"2026-09-09T01:22:13.600895Z","steps":["trace[833998330] 'read index received' (duration: 96.067219ms)","trace[833998330] 'applied index is now lower than readState.Index' (duration: 37.238225ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:22:13.601172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.559773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-09T01:22:13.601528Z","caller":"traceutil/trace.go:171","msg":"trace[638930373] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8944; }","duration":"133.933574ms","start":"2026-09-09T01:22:13.467523Z","end":"2026-09-09T01:22:13.601457Z","steps":["trace[638930373] 'agreement among raft nodes before linearized reading' (duration: 133.455709ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:22:41.38888Z","caller":"traceutil/trace.go:171","msg":"trace[739214184] linearizableReadLoop","detail":"{readStateIndex:9470; appliedIndex:9468; }","duration":"155.215903ms","start":"2026-09-09T01:22:41.233642Z","end":"2026-09-09T01:22:41.388858Z","steps":["trace[739214184] 'read index received' (duration: 154.561572ms)","trace[739214184] 'applied index is now lower than readState.Index' (duration: 653.451µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:22:41.389245Z","caller":"traceutil/trace.go:171","msg":"trace[1481973659] transaction","detail":"{read_only:false; response_revision:9145; number_of_response:1; }","duration":"194.773199ms","start":"2026-09-09T01:22:41.194427Z","end":"2026-09-09T01:22:41.3892Z","steps":["trace[1481973659] 'process raft request' (duration: 194.379697ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:22:41.389244Z","caller":"traceutil/trace.go:171","msg":"trace[141660755] transaction","detail":"{read_only:false; response_revision:9144; number_of_response:1; }","duration":"196.714087ms","start":"2026-09-09T01:22:41.192492Z","end":"2026-09-09T01:22:41.389206Z","steps":["trace[141660755] 'process raft request' (duration: 195.752847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:41.389323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.643655ms","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-09-09T01:22:41.389418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.684346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T01:22:41.389449Z","caller":"traceutil/trace.go:171","msg":"trace[1729485381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9145; }","duration":"155.83225ms","start":"2026-09-09T01:22:41.233593Z","end":"2026-09-09T01:22:41.389425Z","steps":["trace[1729485381] 'agreement among raft nodes before linearized reading' (duration: 155.47335ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:22:41.389485Z","caller":"traceutil/trace.go:171","msg":"trace[1558955182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9145; }","duration":"136.79795ms","start":"2026-09-09T01:22:41.252666Z","end":"2026-09-09T01:22:41.389464Z","steps":["trace[1558955182] 'agreement among raft nodes before linearized reading' (duration: 136.598324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:43.063512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.89183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:22:43.063609Z","caller":"traceutil/trace.go:171","msg":"trace[530020374] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:9151; }","duration":"273.006183ms","start":"2026-09-09T01:22:42.790581Z","end":"2026-09-09T01:22:43.063587Z","steps":["trace[530020374] 'count revisions from in-memory index tree' (duration: 272.793958ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:22:43.063608Z","caller":"traceutil/trace.go:171","msg":"trace[875322043] transaction","detail":"{read_only:false; response_revision:9152; number_of_response:1; }","duration":"135.314076ms","start":"2026-09-09T01:22:42.928265Z","end":"2026-09-09T01:22:43.063579Z","steps":["trace[875322043] 'process raft request' (duration: 124.434934ms)","trace[875322043] 'compare' (duration: 10.667006ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:22:46.131833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.288465ms","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-09-09T01:22:46.131945Z","caller":"traceutil/trace.go:171","msg":"trace[879757446] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:9170; }","duration":"116.419569ms","start":"2026-09-09T01:22:46.015483Z","end":"2026-09-09T01:22:46.131902Z","steps":["trace[879757446] 'count revisions from in-memory index tree' (duration: 116.223464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:54.831235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.537447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-09T01:22:54.831311Z","caller":"traceutil/trace.go:171","msg":"trace[1768020339] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9216; }","duration":"287.660161ms","start":"2026-09-09T01:22:54.543637Z","end":"2026-09-09T01:22:54.831297Z","steps":["trace[1768020339] 'range keys from in-memory index tree' (duration: 287.390752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:54.924307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.823785ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-09T01:22:55.385372Z","caller":"traceutil/trace.go:171","msg":"trace[1522166968] transaction","detail":"{read_only:false; response_revision:9218; number_of_response:1; }","duration":"124.191026ms","start":"2026-09-09T01:22:55.261134Z","end":"2026-09-09T01:22:55.385325Z","steps":["trace[1522166968] 'process raft request' (duration: 123.98894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:55.82857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.26985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:22:55.828651Z","caller":"traceutil/trace.go:171","msg":"trace[1210752866] linearizableReadLoop","detail":"{readStateIndex:9549; appliedIndex:9547; }","duration":"362.956977ms","start":"2026-09-09T01:22:55.465681Z","end":"2026-09-09T01:22:55.828638Z","steps":["trace[1210752866] 'read index received' (duration: 7.836359ms)","trace[1210752866] 'applied index is now lower than readState.Index' (duration: 355.119128ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:22:55.828725Z","caller":"traceutil/trace.go:171","msg":"trace[1636299281] transaction","detail":"{read_only:false; response_revision:9221; number_of_response:1; }","duration":"387.468984ms","start":"2026-09-09T01:22:55.441248Z","end":"2026-09-09T01:22:55.828717Z","steps":["trace[1636299281] 'process raft request' (duration: 76.972507ms)","trace[1636299281] 'compare' (duration: 310.024463ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:22:55.828766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:22:55.441188Z","time spent":"387.558477ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:22:55.828854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.004446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-09T01:22:55.828925Z","caller":"traceutil/trace.go:171","msg":"trace[1888879573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9221; }","duration":"360.11918ms","start":"2026-09-09T01:22:55.468787Z","end":"2026-09-09T01:22:55.828907Z","steps":["trace[1888879573] 'agreement among raft nodes before linearized reading' (duration: 359.943564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:55.828965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:22:55.46877Z","time spent":"360.187151ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-09T01:22:55.829071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.399749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T01:22:55.8291Z","caller":"traceutil/trace.go:171","msg":"trace[591680540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9221; }","duration":"363.468581ms","start":"2026-09-09T01:22:55.465623Z","end":"2026-09-09T01:22:55.829092Z","steps":["trace[591680540] 'agreement among raft nodes before linearized reading' (duration: 363.358138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:55.829076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.795262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cz5q7\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-09-09T01:22:55.82911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.348934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-09T01:22:55.829159Z","caller":"traceutil/trace.go:171","msg":"trace[183533733] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cz5q7; range_end:; response_count:1; response_revision:9221; }","duration":"200.920096ms","start":"2026-09-09T01:22:55.628219Z","end":"2026-09-09T01:22:55.829139Z","steps":["trace[183533733] 'agreement among raft nodes before linearized reading' (duration: 200.686959ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:22:55.829198Z","caller":"traceutil/trace.go:171","msg":"trace[495248821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9221; }","duration":"292.491898ms","start":"2026-09-09T01:22:55.536686Z","end":"2026-09-09T01:22:55.829178Z","steps":["trace[495248821] 'agreement among raft nodes before linearized reading' (duration: 292.252131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:22:55.829119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:22:55.4656Z","time spent":"363.515183ms","remote":"127.0.0.1:41168","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-09-09T01:23:41.341546Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"54232768b5552f01","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T01:23:41.34828Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-09T01:23:41.348445Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-09T01:24:12.567484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.759261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-09T01:24:12.567575Z","caller":"traceutil/trace.go:171","msg":"trace[1463409166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10008; }","duration":"131.872824ms","start":"2026-09-09T01:24:12.435683Z","end":"2026-09-09T01:24:12.567555Z","steps":["trace[1463409166] 'range keys from in-memory index tree' (duration: 131.537494ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:26.388696Z","caller":"traceutil/trace.go:171","msg":"trace[613775974] transaction","detail":"{read_only:false; response_revision:10085; number_of_response:1; }","duration":"227.995066ms","start":"2026-09-09T01:24:26.160666Z","end":"2026-09-09T01:24:26.388661Z","steps":["trace[613775974] 'process raft request' (duration: 227.480019ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:26.583177Z","caller":"traceutil/trace.go:171","msg":"trace[1255233825] linearizableReadLoop","detail":"{readStateIndex:10442; appliedIndex:10439; }","duration":"114.203674ms","start":"2026-09-09T01:24:26.468955Z","end":"2026-09-09T01:24:26.583159Z","steps":["trace[1255233825] 'read index received' (duration: 20.716504ms)","trace[1255233825] 'applied index is now lower than readState.Index' (duration: 93.48594ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:24:26.583307Z","caller":"traceutil/trace.go:171","msg":"trace[571439497] transaction","detail":"{read_only:false; response_revision:10088; number_of_response:1; }","duration":"189.171348ms","start":"2026-09-09T01:24:26.394126Z","end":"2026-09-09T01:24:26.583297Z","steps":["trace[571439497] 'process raft request' (duration: 188.994172ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:26.583313Z","caller":"traceutil/trace.go:171","msg":"trace[1667571496] transaction","detail":"{read_only:false; response_revision:10087; number_of_response:1; }","duration":"189.200228ms","start":"2026-09-09T01:24:26.394056Z","end":"2026-09-09T01:24:26.583256Z","steps":["trace[1667571496] 'process raft request' (duration: 171.509547ms)","trace[1667571496] 'compare' (duration: 17.464094ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:24:26.583673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.752665ms","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-09-09T01:24:26.5837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.683489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cz5q7\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T01:24:26.583811Z","caller":"traceutil/trace.go:171","msg":"trace[1135144393] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:10088; }","duration":"105.115906ms","start":"2026-09-09T01:24:26.478668Z","end":"2026-09-09T01:24:26.583784Z","steps":["trace[1135144393] 'agreement among raft nodes before linearized reading' (duration: 104.737195ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:26.583835Z","caller":"traceutil/trace.go:171","msg":"trace[74444147] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cz5q7; range_end:; response_count:1; response_revision:10088; }","duration":"114.914036ms","start":"2026-09-09T01:24:26.468886Z","end":"2026-09-09T01:24:26.5838Z","steps":["trace[74444147] 'agreement among raft nodes before linearized reading' (duration: 114.517384ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:25:06.17943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7993} {"level":"info","ts":"2026-09-09T01:25:06.295858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7993,"took":"113.878184ms","hash":2232198714,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":25452544,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-09T01:25:06.295948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2232198714,"revision":7993,"compact-revision":5304} {"level":"info","ts":"2026-09-09T01:29:45.287843Z","caller":"traceutil/trace.go:171","msg":"trace[1226335674] linearizableReadLoop","detail":"{readStateIndex:12832; appliedIndex:12831; }","duration":"102.988745ms","start":"2026-09-09T01:29:45.18483Z","end":"2026-09-09T01:29:45.287819Z","steps":["trace[1226335674] 'read index received' (duration: 102.80044ms)","trace[1226335674] 'applied index is now lower than readState.Index' (duration: 187.575µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:29:45.287893Z","caller":"traceutil/trace.go:171","msg":"trace[1497404263] transaction","detail":"{read_only:false; response_revision:12404; number_of_response:1; }","duration":"113.566638ms","start":"2026-09-09T01:29:45.174298Z","end":"2026-09-09T01:29:45.287864Z","steps":["trace[1497404263] 'process raft request' (duration: 113.380232ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:29:45.288043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.191272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cz5q7\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T01:29:45.288083Z","caller":"traceutil/trace.go:171","msg":"trace[864097154] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cz5q7; range_end:; response_count:1; response_revision:12404; }","duration":"103.270444ms","start":"2026-09-09T01:29:45.184802Z","end":"2026-09-09T01:29:45.288072Z","steps":["trace[864097154] 'agreement among raft nodes before linearized reading' (duration: 103.12612ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:30:04.473715Z","caller":"traceutil/trace.go:171","msg":"trace[1475297554] linearizableReadLoop","detail":"{readStateIndex:12942; appliedIndex:12941; }","duration":"139.799337ms","start":"2026-09-09T01:30:04.333864Z","end":"2026-09-09T01:30:04.473663Z","steps":["trace[1475297554] 'read index received' (duration: 139.705004ms)","trace[1475297554] 'applied index is now lower than readState.Index' (duration: 93.233µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:30:04.473914Z","caller":"traceutil/trace.go:171","msg":"trace[161334633] transaction","detail":"{read_only:false; response_revision:12511; number_of_response:1; }","duration":"157.732942ms","start":"2026-09-09T01:30:04.31613Z","end":"2026-09-09T01:30:04.473862Z","steps":["trace[161334633] 'process raft request' (duration: 157.364441ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:30:04.474183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.307923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-09T01:30:04.474243Z","caller":"traceutil/trace.go:171","msg":"trace[319414643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12511; }","duration":"140.426787ms","start":"2026-09-09T01:30:04.333796Z","end":"2026-09-09T01:30:04.474223Z","steps":["trace[319414643] 'agreement among raft nodes before linearized reading' (duration: 140.028094ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:30:06.187626Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10374} {"level":"info","ts":"2026-09-09T01:30:06.270478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10374,"took":"80.266723ms","hash":3465723177,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":25186304,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-09T01:30:06.270526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3465723177,"revision":10374,"compact-revision":7993} {"level":"info","ts":"2026-09-09T01:35:06.19672Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12523} {"level":"info","ts":"2026-09-09T01:35:06.283604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12523,"took":"83.021542ms","hash":3362447067,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":27746304,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T01:35:06.283701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3362447067,"revision":12523,"compact-revision":10374} {"level":"info","ts":"2026-09-09T01:36:23.084765Z","caller":"traceutil/trace.go:171","msg":"trace[1216910751] transaction","detail":"{read_only:false; response_revision:15051; number_of_response:1; }","duration":"146.844249ms","start":"2026-09-09T01:36:22.937896Z","end":"2026-09-09T01:36:23.08474Z","steps":["trace[1216910751] 'process raft request' (duration: 143.456866ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:37:02.686263Z","caller":"traceutil/trace.go:171","msg":"trace[2084417383] transaction","detail":"{read_only:false; response_revision:15266; number_of_response:1; }","duration":"234.174339ms","start":"2026-09-09T01:37:02.452059Z","end":"2026-09-09T01:37:02.686233Z","steps":["trace[2084417383] 'process raft request' (duration: 234.009703ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:37:02.836653Z","caller":"traceutil/trace.go:171","msg":"trace[131083657] linearizableReadLoop","detail":"{readStateIndex:15800; appliedIndex:15798; }","duration":"166.001013ms","start":"2026-09-09T01:37:02.670625Z","end":"2026-09-09T01:37:02.836626Z","steps":["trace[131083657] 'read index received' (duration: 16.245405ms)","trace[131083657] 'applied index is now lower than readState.Index' (duration: 149.754518ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:37:02.83672Z","caller":"traceutil/trace.go:171","msg":"trace[1852540220] transaction","detail":"{read_only:false; response_revision:15267; number_of_response:1; }","duration":"297.416533ms","start":"2026-09-09T01:37:02.539236Z","end":"2026-09-09T01:37:02.836653Z","steps":["trace[1852540220] 'process raft request' (duration: 249.786403ms)","trace[1852540220] 'compare' (duration: 47.403713ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:37:02.836809Z","caller":"traceutil/trace.go:171","msg":"trace[2033016449] transaction","detail":"{read_only:false; response_revision:15268; number_of_response:1; }","duration":"226.941128ms","start":"2026-09-09T01:37:02.609852Z","end":"2026-09-09T01:37:02.836793Z","steps":["trace[2033016449] 'process raft request' (duration: 226.723572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:37:02.837048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.405085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-09T01:37:02.83709Z","caller":"traceutil/trace.go:171","msg":"trace[2040358636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15268; }","duration":"166.500199ms","start":"2026-09-09T01:37:02.670579Z","end":"2026-09-09T01:37:02.837079Z","steps":["trace[2040358636] 'agreement among raft nodes before linearized reading' (duration: 166.358764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:37:02.837282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.301237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-09T01:37:02.837362Z","caller":"traceutil/trace.go:171","msg":"trace[361864994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15268; }","duration":"108.435032ms","start":"2026-09-09T01:37:02.728907Z","end":"2026-09-09T01:37:02.837342Z","steps":["trace[361864994] 'agreement among raft nodes before linearized reading' (duration: 108.180803ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:37:14.203275Z","caller":"traceutil/trace.go:171","msg":"trace[453283666] transaction","detail":"{read_only:false; response_revision:15450; number_of_response:1; }","duration":"194.856751ms","start":"2026-09-09T01:37:14.008391Z","end":"2026-09-09T01:37:14.203247Z","steps":["trace[453283666] 'process raft request' (duration: 194.703817ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:38:51.662048Z","caller":"traceutil/trace.go:171","msg":"trace[392647282] transaction","detail":"{read_only:false; response_revision:16530; number_of_response:1; }","duration":"105.306426ms","start":"2026-09-09T01:38:51.556707Z","end":"2026-09-09T01:38:51.662014Z","steps":["trace[392647282] 'process raft request' (duration: 52.826909ms)","trace[392647282] 'compare' (duration: 52.216469ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:38:51.672135Z","caller":"traceutil/trace.go:171","msg":"trace[2037078320] transaction","detail":"{read_only:false; response_revision:16532; number_of_response:1; }","duration":"109.910315ms","start":"2026-09-09T01:38:51.562173Z","end":"2026-09-09T01:38:51.672083Z","steps":["trace[2037078320] 'process raft request' (duration: 109.446161ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:38:51.672279Z","caller":"traceutil/trace.go:171","msg":"trace[1601618697] transaction","detail":"{read_only:false; response_revision:16531; number_of_response:1; }","duration":"113.368831ms","start":"2026-09-09T01:38:51.558877Z","end":"2026-09-09T01:38:51.672246Z","steps":["trace[1601618697] 'process raft request' (duration: 112.639189ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:38:52.100721Z","caller":"traceutil/trace.go:171","msg":"trace[692385019] linearizableReadLoop","detail":"{readStateIndex:17101; appliedIndex:17100; }","duration":"154.785681ms","start":"2026-09-09T01:38:51.945887Z","end":"2026-09-09T01:38:52.100673Z","steps":["trace[692385019] 'read index received' (duration: 154.623666ms)","trace[692385019] 'applied index is now lower than readState.Index' (duration: 160.915µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:38:52.100784Z","caller":"traceutil/trace.go:171","msg":"trace[88550309] transaction","detail":"{read_only:false; response_revision:16533; number_of_response:1; }","duration":"158.856375ms","start":"2026-09-09T01:38:51.941891Z","end":"2026-09-09T01:38:52.100748Z","steps":["trace[88550309] 'process raft request' (duration: 158.620498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:38:52.100957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.044209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-09-09T01:38:52.101005Z","caller":"traceutil/trace.go:171","msg":"trace[1699282122] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:16533; }","duration":"155.127362ms","start":"2026-09-09T01:38:51.945862Z","end":"2026-09-09T01:38:52.10099Z","steps":["trace[1699282122] 'agreement among raft nodes before linearized reading' (duration: 154.935487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:38:52.372544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.026569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:38:52.372661Z","caller":"traceutil/trace.go:171","msg":"trace[1290778119] linearizableReadLoop","detail":"{readStateIndex:17102; appliedIndex:17101; }","duration":"180.824034ms","start":"2026-09-09T01:38:52.19182Z","end":"2026-09-09T01:38:52.372644Z","steps":["trace[1290778119] 'read index received' (duration: 41.971µs)","trace[1290778119] 'applied index is now lower than readState.Index' (duration: 180.780663ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:38:52.372736Z","caller":"traceutil/trace.go:171","msg":"trace[1864242075] transaction","detail":"{read_only:false; response_revision:16534; number_of_response:1; }","duration":"392.062304ms","start":"2026-09-09T01:38:51.980647Z","end":"2026-09-09T01:38:52.37271Z","steps":["trace[1864242075] 'process raft request' (duration: 191.737226ms)","trace[1864242075] 'compare' (duration: 199.655447ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:38:52.372769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.947928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:38:52.372802Z","caller":"traceutil/trace.go:171","msg":"trace[906251052] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16534; }","duration":"181.01985ms","start":"2026-09-09T01:38:52.191773Z","end":"2026-09-09T01:38:52.372793Z","steps":["trace[906251052] 'agreement among raft nodes before linearized reading' (duration: 180.956419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:38:52.372853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:38:51.980607Z","time spent":"392.182077ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:38:52.372936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.743602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-09-09T01:38:52.373008Z","caller":"traceutil/trace.go:171","msg":"trace[964185661] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16534; }","duration":"180.844314ms","start":"2026-09-09T01:38:52.192146Z","end":"2026-09-09T01:38:52.37299Z","steps":["trace[964185661] 'agreement among raft nodes before linearized reading' (duration: 180.616948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:38:52.372997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.181235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-09-09T01:38:52.373091Z","caller":"traceutil/trace.go:171","msg":"trace[1675783085] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16534; }","duration":"180.297529ms","start":"2026-09-09T01:38:52.19277Z","end":"2026-09-09T01:38:52.373068Z","steps":["trace[1675783085] 'agreement among raft nodes before linearized reading' (duration: 180.043621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:38:52.373142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.454763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-09T01:38:52.373171Z","caller":"traceutil/trace.go:171","msg":"trace[1832047488] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16534; }","duration":"180.496344ms","start":"2026-09-09T01:38:52.192667Z","end":"2026-09-09T01:38:52.373164Z","steps":["trace[1832047488] 'agreement among raft nodes before linearized reading' (duration: 180.395381ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:38:52.373285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.637889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:38:52.373322Z","caller":"traceutil/trace.go:171","msg":"trace[210036128] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16534; }","duration":"180.67487ms","start":"2026-09-09T01:38:52.192637Z","end":"2026-09-09T01:38:52.373312Z","steps":["trace[210036128] 'agreement among raft nodes before linearized reading' (duration: 180.570407ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:40:06.204957Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14553} {"level":"info","ts":"2026-09-09T01:40:06.293962Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14553,"took":"86.362819ms","hash":3838635077,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":31006720,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T01:40:06.294035Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3838635077,"revision":14553,"compact-revision":12523} {"level":"info","ts":"2026-09-09T01:44:30.9467Z","caller":"traceutil/trace.go:171","msg":"trace[1202737256] transaction","detail":"{read_only:false; response_revision:18951; number_of_response:1; }","duration":"236.51168ms","start":"2026-09-09T01:44:30.71015Z","end":"2026-09-09T01:44:30.946662Z","steps":["trace[1202737256] 'process raft request' (duration: 153.535654ms)","trace[1202737256] 'compare' (duration: 82.41392ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:44:35.843106Z","caller":"traceutil/trace.go:171","msg":"trace[263008571] linearizableReadLoop","detail":"{readStateIndex:19626; appliedIndex:19624; }","duration":"282.472011ms","start":"2026-09-09T01:44:35.560605Z","end":"2026-09-09T01:44:35.843077Z","steps":["trace[263008571] 'read index received' (duration: 12.977565ms)","trace[263008571] 'applied index is now lower than readState.Index' (duration: 269.493026ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:44:35.843245Z","caller":"traceutil/trace.go:171","msg":"trace[2139762367] transaction","detail":"{read_only:false; response_revision:18977; number_of_response:1; }","duration":"285.528724ms","start":"2026-09-09T01:44:35.557704Z","end":"2026-09-09T01:44:35.843232Z","steps":["trace[2139762367] 'process raft request' (duration: 282.048467ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:35.844698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.063629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:940194"} {"level":"info","ts":"2026-09-09T01:44:35.844847Z","caller":"traceutil/trace.go:171","msg":"trace[986806553] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18977; }","duration":"284.253705ms","start":"2026-09-09T01:44:35.560571Z","end":"2026-09-09T01:44:35.844825Z","steps":["trace[986806553] 'agreement among raft nodes before linearized reading' (duration: 282.703668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:35.855471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.762431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-09T01:44:35.855507Z","caller":"traceutil/trace.go:171","msg":"trace[946745511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18978; }","duration":"219.839313ms","start":"2026-09-09T01:44:35.635659Z","end":"2026-09-09T01:44:35.855499Z","steps":["trace[946745511] 'agreement among raft nodes before linearized reading' (duration: 219.72142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:36.236603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.935273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-09T01:44:36.236706Z","caller":"traceutil/trace.go:171","msg":"trace[1513583199] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18979; }","duration":"187.086397ms","start":"2026-09-09T01:44:36.049583Z","end":"2026-09-09T01:44:36.236669Z","steps":["trace[1513583199] 'range keys from in-memory index tree' (duration: 186.723965ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:38.092988Z","caller":"traceutil/trace.go:171","msg":"trace[1800804209] linearizableReadLoop","detail":"{readStateIndex:19641; appliedIndex:19640; }","duration":"133.474214ms","start":"2026-09-09T01:44:37.959491Z","end":"2026-09-09T01:44:38.092965Z","steps":["trace[1800804209] 'read index received' (duration: 93.760805ms)","trace[1800804209] 'applied index is now lower than readState.Index' (duration: 39.712479ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:44:38.093306Z","caller":"traceutil/trace.go:171","msg":"trace[1169040001] transaction","detail":"{read_only:false; response_revision:18992; number_of_response:1; }","duration":"137.25107ms","start":"2026-09-09T01:44:37.956035Z","end":"2026-09-09T01:44:38.093286Z","steps":["trace[1169040001] 'process raft request' (duration: 97.369814ms)","trace[1169040001] 'compare' (duration: 39.340458ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:44:38.094746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.238098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:940380"} {"level":"info","ts":"2026-09-09T01:44:38.094805Z","caller":"traceutil/trace.go:171","msg":"trace[1653810681] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18992; }","duration":"135.359441ms","start":"2026-09-09T01:44:37.959432Z","end":"2026-09-09T01:44:38.094792Z","steps":["trace[1653810681] 'agreement among raft nodes before linearized reading' (duration: 134.047871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:38.471896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.244903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-09T01:44:38.471982Z","caller":"traceutil/trace.go:171","msg":"trace[1680291075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18992; }","duration":"214.404357ms","start":"2026-09-09T01:44:38.257556Z","end":"2026-09-09T01:44:38.47196Z","steps":["trace[1680291075] 'range keys from in-memory index tree' (duration: 214.064838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:38.472345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.023336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-09-09T01:44:38.472388Z","caller":"traceutil/trace.go:171","msg":"trace[300563303] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18992; }","duration":"169.085628ms","start":"2026-09-09T01:44:38.303291Z","end":"2026-09-09T01:44:38.472377Z","steps":["trace[300563303] 'range keys from in-memory index tree' (duration: 168.940423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:38.472608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.341146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-09-09T01:44:38.472649Z","caller":"traceutil/trace.go:171","msg":"trace[363026394] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18992; }","duration":"168.403898ms","start":"2026-09-09T01:44:38.304236Z","end":"2026-09-09T01:44:38.47264Z","steps":["trace[363026394] 'range keys from in-memory index tree' (duration: 168.257204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:38.473385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.254323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-rfrbv\" ","response":"range_response_count:1 size:20736"} {"level":"info","ts":"2026-09-09T01:44:38.473437Z","caller":"traceutil/trace.go:171","msg":"trace[40237349] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-rfrbv; range_end:; response_count:1; response_revision:18992; }","duration":"149.343328ms","start":"2026-09-09T01:44:38.32408Z","end":"2026-09-09T01:44:38.473423Z","steps":["trace[40237349] 'range keys from in-memory index tree' (duration: 149.1309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:38.473677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.247091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:44:38.473723Z","caller":"traceutil/trace.go:171","msg":"trace[2009554174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18992; }","duration":"145.321844ms","start":"2026-09-09T01:44:38.32839Z","end":"2026-09-09T01:44:38.473712Z","steps":["trace[2009554174] 'range keys from in-memory index tree' (duration: 145.18572ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:38.488123Z","caller":"traceutil/trace.go:171","msg":"trace[925343062] linearizableReadLoop","detail":"{readStateIndex:19642; appliedIndex:19641; }","duration":"140.763986ms","start":"2026-09-09T01:44:38.347326Z","end":"2026-09-09T01:44:38.48809Z","steps":["trace[925343062] 'read index received' (duration: 140.631762ms)","trace[925343062] 'applied index is now lower than readState.Index' (duration: 131.024µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:44:38.488188Z","caller":"traceutil/trace.go:171","msg":"trace[1847965658] transaction","detail":"{read_only:false; response_revision:18993; number_of_response:1; }","duration":"150.192044ms","start":"2026-09-09T01:44:38.337952Z","end":"2026-09-09T01:44:38.488144Z","steps":["trace[1847965658] 'process raft request' (duration: 149.939665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:38.488343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.976812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T01:44:38.488411Z","caller":"traceutil/trace.go:171","msg":"trace[1298685609] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:18993; }","duration":"141.108467ms","start":"2026-09-09T01:44:38.347283Z","end":"2026-09-09T01:44:38.488391Z","steps":["trace[1298685609] 'agreement among raft nodes before linearized reading' (duration: 140.971773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:38.53632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.385012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-ckg66\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-09-09T01:44:38.536402Z","caller":"traceutil/trace.go:171","msg":"trace[154521079] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-ckg66; range_end:; response_count:1; response_revision:18994; }","duration":"180.539297ms","start":"2026-09-09T01:44:38.355844Z","end":"2026-09-09T01:44:38.536384Z","steps":["trace[154521079] 'agreement among raft nodes before linearized reading' (duration: 180.30962ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:59.19797Z","caller":"traceutil/trace.go:171","msg":"trace[1934144590] transaction","detail":"{read_only:false; response_revision:19241; number_of_response:1; }","duration":"116.60459ms","start":"2026-09-09T01:44:59.081326Z","end":"2026-09-09T01:44:59.197931Z","steps":["trace[1934144590] 'process raft request' (duration: 116.384424ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:45:06.22013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16997} {"level":"info","ts":"2026-09-09T01:45:06.330162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16997,"took":"105.914015ms","hash":3587256562,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":31547392,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T01:45:06.330381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3587256562,"revision":16997,"compact-revision":14553} {"level":"info","ts":"2026-09-09T01:45:09.448215Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"54232768b5552f01","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T01:45:09.456845Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-09T01:45:09.457023Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-09T01:50:06.235874Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19307} {"level":"info","ts":"2026-09-09T01:50:06.329117Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19307,"took":"89.961091ms","hash":575036229,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":34324480,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-09T01:50:06.329176Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":575036229,"revision":19307,"compact-revision":16997} {"level":"info","ts":"2026-09-09T01:51:26.63868Z","caller":"traceutil/trace.go:171","msg":"trace[1899535385] transaction","detail":"{read_only:false; response_revision:22103; number_of_response:1; }","duration":"179.395957ms","start":"2026-09-09T01:51:26.459231Z","end":"2026-09-09T01:51:26.638627Z","steps":["trace[1899535385] 'process raft request' (duration: 156.248841ms)","trace[1899535385] 'compare' (duration: 22.878007ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:51:42.089418Z","caller":"traceutil/trace.go:171","msg":"trace[1644178008] transaction","detail":"{read_only:false; response_revision:22182; number_of_response:1; }","duration":"151.418034ms","start":"2026-09-09T01:51:41.937956Z","end":"2026-09-09T01:51:42.089374Z","steps":["trace[1644178008] 'process raft request' (duration: 151.193077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:48.002885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.266617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-09T01:51:48.002918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.826888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T01:51:48.003031Z","caller":"traceutil/trace.go:171","msg":"trace[981953998] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:22209; }","duration":"198.028103ms","start":"2026-09-09T01:51:47.804977Z","end":"2026-09-09T01:51:48.003005Z","steps":["trace[981953998] 'count revisions from in-memory index tree' (duration: 197.720245ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:51:48.002962Z","caller":"traceutil/trace.go:171","msg":"trace[1989544471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22209; }","duration":"124.390451ms","start":"2026-09-09T01:51:47.878552Z","end":"2026-09-09T01:51:48.002942Z","steps":["trace[1989544471] 'range keys from in-memory index tree' (duration: 124.004489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:48.002918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.718068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T01:51:48.003257Z","caller":"traceutil/trace.go:171","msg":"trace[934730705] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:22209; }","duration":"184.11857ms","start":"2026-09-09T01:51:47.819097Z","end":"2026-09-09T01:51:48.003216Z","steps":["trace[934730705] 'count revisions from in-memory index tree' (duration: 183.558944ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:51:48.230381Z","caller":"traceutil/trace.go:171","msg":"trace[1699703573] linearizableReadLoop","detail":"{readStateIndex:22968; appliedIndex:22967; }","duration":"179.921063ms","start":"2026-09-09T01:51:48.050432Z","end":"2026-09-09T01:51:48.230353Z","steps":["trace[1699703573] 'read index received' (duration: 179.764338ms)","trace[1699703573] 'applied index is now lower than readState.Index' (duration: 155.835µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:51:48.230463Z","caller":"traceutil/trace.go:171","msg":"trace[1882267572] transaction","detail":"{read_only:false; response_revision:22210; number_of_response:1; }","duration":"222.606673ms","start":"2026-09-09T01:51:48.007824Z","end":"2026-09-09T01:51:48.230431Z","steps":["trace[1882267572] 'process raft request' (duration: 222.378286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:48.230574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.132979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:51:48.230628Z","caller":"traceutil/trace.go:171","msg":"trace[638101032] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:22210; }","duration":"180.211761ms","start":"2026-09-09T01:51:48.0504Z","end":"2026-09-09T01:51:48.230612Z","steps":["trace[638101032] 'agreement among raft nodes before linearized reading' (duration: 180.108028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:48.482654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.281682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:51:48.482754Z","caller":"traceutil/trace.go:171","msg":"trace[1744618437] linearizableReadLoop","detail":"{readStateIndex:22969; appliedIndex:22968; }","duration":"252.257796ms","start":"2026-09-09T01:51:48.23048Z","end":"2026-09-09T01:51:48.482738Z","steps":["trace[1744618437] 'read index received' (duration: 140.704017ms)","trace[1744618437] 'applied index is now lower than readState.Index' (duration: 111.551229ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:51:48.482781Z","caller":"traceutil/trace.go:171","msg":"trace[2053669015] transaction","detail":"{read_only:false; response_revision:22211; number_of_response:1; }","duration":"317.748513ms","start":"2026-09-09T01:51:48.165006Z","end":"2026-09-09T01:51:48.482755Z","steps":["trace[2053669015] 'process raft request' (duration: 206.250095ms)","trace[2053669015] 'compare' (duration: 111.148117ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:51:48.482892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:51:48.164987Z","time spent":"317.860125ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:51:48.482962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.278007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-09T01:51:48.48301Z","caller":"traceutil/trace.go:171","msg":"trace[1959552557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22211; }","duration":"357.3598ms","start":"2026-09-09T01:51:48.125626Z","end":"2026-09-09T01:51:48.482986Z","steps":["trace[1959552557] 'agreement among raft nodes before linearized reading' (duration: 357.239226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:48.483043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:51:48.125602Z","time spent":"357.434051ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-09T01:51:48.483089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.632871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:51:48.483143Z","caller":"traceutil/trace.go:171","msg":"trace[748348784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22211; }","duration":"154.823157ms","start":"2026-09-09T01:51:48.328307Z","end":"2026-09-09T01:51:48.48313Z","steps":["trace[748348784] 'agreement among raft nodes before linearized reading' (duration: 154.740294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:48.483264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.914418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-09T01:51:48.483365Z","caller":"traceutil/trace.go:171","msg":"trace[1336162536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22211; }","duration":"171.025291ms","start":"2026-09-09T01:51:48.312317Z","end":"2026-09-09T01:51:48.483342Z","steps":["trace[1336162536] 'agreement among raft nodes before linearized reading' (duration: 170.707032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:48.862495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.313743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:51:48.862633Z","caller":"traceutil/trace.go:171","msg":"trace[729345247] transaction","detail":"{read_only:false; response_revision:22212; number_of_response:1; }","duration":"374.772269ms","start":"2026-09-09T01:51:48.487834Z","end":"2026-09-09T01:51:48.862606Z","steps":["trace[729345247] 'process raft request' (duration: 156.219699ms)","trace[729345247] 'compare' (duration: 217.961183ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:51:48.862718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:51:48.487819Z","time spent":"374.856132ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:51:48.865044Z","caller":"traceutil/trace.go:171","msg":"trace[467672459] transaction","detail":"{read_only:false; response_revision:22214; number_of_response:1; }","duration":"345.726485ms","start":"2026-09-09T01:51:48.519298Z","end":"2026-09-09T01:51:48.865024Z","steps":["trace[467672459] 'process raft request' (duration: 345.632502ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:51:48.865081Z","caller":"traceutil/trace.go:171","msg":"trace[1299614159] transaction","detail":"{read_only:false; response_revision:22213; number_of_response:1; }","duration":"377.180243ms","start":"2026-09-09T01:51:48.487859Z","end":"2026-09-09T01:51:48.865039Z","steps":["trace[1299614159] 'process raft request' (duration: 376.923525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:48.865146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:51:48.519269Z","time spent":"345.828148ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:51:48.865408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:51:48.487841Z","time spent":"377.358298ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:51:48.865846Z","caller":"traceutil/trace.go:171","msg":"trace[1431429752] linearizableReadLoop","detail":"{readStateIndex:22973; appliedIndex:22969; }","duration":"275.748153ms","start":"2026-09-09T01:51:48.590077Z","end":"2026-09-09T01:51:48.865825Z","steps":["trace[1431429752] 'read index received' (duration: 53.989925ms)","trace[1431429752] 'applied index is now lower than readState.Index' (duration: 221.757098ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:51:48.866143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.057732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:51:48.866219Z","caller":"traceutil/trace.go:171","msg":"trace[1279066619] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:22215; }","duration":"276.177555ms","start":"2026-09-09T01:51:48.590023Z","end":"2026-09-09T01:51:48.866201Z","steps":["trace[1279066619] 'agreement among raft nodes before linearized reading' (duration: 275.970419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:48.866515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.850599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-09T01:51:48.866579Z","caller":"traceutil/trace.go:171","msg":"trace[348070278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22215; }","duration":"112.999394ms","start":"2026-09-09T01:51:48.753562Z","end":"2026-09-09T01:51:48.866562Z","steps":["trace[348070278] 'agreement among raft nodes before linearized reading' (duration: 112.663434ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:51:48.866301Z","caller":"traceutil/trace.go:171","msg":"trace[1161874670] transaction","detail":"{read_only:false; response_revision:22215; number_of_response:1; }","duration":"319.425513ms","start":"2026-09-09T01:51:48.54685Z","end":"2026-09-09T01:51:48.866275Z","steps":["trace[1161874670] 'process raft request' (duration: 318.123294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:48.866801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:51:48.546827Z","time spent":"319.911468ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:51:49.124063Z","caller":"traceutil/trace.go:171","msg":"trace[499952880] transaction","detail":"{read_only:false; response_revision:22216; number_of_response:1; }","duration":"256.022691ms","start":"2026-09-09T01:51:48.868001Z","end":"2026-09-09T01:51:49.124023Z","steps":["trace[499952880] 'process raft request' (duration: 237.185907ms)","trace[499952880] 'compare' (duration: 18.398401ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:51:49.125759Z","caller":"traceutil/trace.go:171","msg":"trace[1651747072] linearizableReadLoop","detail":"{readStateIndex:22976; appliedIndex:22973; }","duration":"181.571783ms","start":"2026-09-09T01:51:48.944163Z","end":"2026-09-09T01:51:49.125735Z","steps":["trace[1651747072] 'read index received' (duration: 161.122311ms)","trace[1651747072] 'applied index is now lower than readState.Index' (duration: 20.448302ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:51:49.125797Z","caller":"traceutil/trace.go:171","msg":"trace[1331930487] transaction","detail":"{read_only:false; response_revision:22217; number_of_response:1; }","duration":"256.710772ms","start":"2026-09-09T01:51:48.869058Z","end":"2026-09-09T01:51:49.125769Z","steps":["trace[1331930487] 'process raft request' (duration: 256.518287ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:51:49.125856Z","caller":"traceutil/trace.go:171","msg":"trace[1240309184] transaction","detail":"{read_only:false; response_revision:22218; number_of_response:1; }","duration":"255.037562ms","start":"2026-09-09T01:51:48.870786Z","end":"2026-09-09T01:51:49.125823Z","steps":["trace[1240309184] 'process raft request' (duration: 254.907128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:49.126015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.842691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-09T01:51:49.126063Z","caller":"traceutil/trace.go:171","msg":"trace[2146040440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22218; }","duration":"181.955195ms","start":"2026-09-09T01:51:48.944092Z","end":"2026-09-09T01:51:49.126048Z","steps":["trace[2146040440] 'agreement among raft nodes before linearized reading' (duration: 181.728028ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:55:06.242969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21641} {"level":"info","ts":"2026-09-09T01:55:06.355567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21641,"took":"108.764655ms","hash":2413042309,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":30310400,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T01:55:06.355628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2413042309,"revision":21641,"compact-revision":19307} {"level":"info","ts":"2026-09-09T01:56:02.96381Z","caller":"traceutil/trace.go:171","msg":"trace[1023326372] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"368.238411ms","start":"2026-09-09T01:56:02.595536Z","end":"2026-09-09T01:56:02.963774Z","steps":["trace[1023326372] 'process raft request' (duration: 364.048604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:56:02.964098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:56:02.595516Z","time spent":"368.479828ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:56:02.967737Z","caller":"traceutil/trace.go:171","msg":"trace[1691446067] transaction","detail":"{read_only:false; response_revision:24761; number_of_response:1; }","duration":"272.889974ms","start":"2026-09-09T01:56:02.694816Z","end":"2026-09-09T01:56:02.967706Z","steps":["trace[1691446067] 'process raft request' (duration: 272.636267ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:56:02.967716Z","caller":"traceutil/trace.go:171","msg":"trace[415973743] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"107.477294ms","start":"2026-09-09T01:56:02.860215Z","end":"2026-09-09T01:56:02.967692Z","steps":["trace[415973743] 'process raft request' (duration: 107.431293ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:56:02.967824Z","caller":"traceutil/trace.go:171","msg":"trace[383124944] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"145.03893ms","start":"2026-09-09T01:56:02.822728Z","end":"2026-09-09T01:56:02.967767Z","steps":["trace[383124944] 'process raft request' (duration: 144.869664ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:59:43.000838Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006bac.wal"} {"level":"info","ts":"2026-09-09T02:00:06.250428Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23764} {"level":"info","ts":"2026-09-09T02:00:06.372249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23764,"took":"116.705894ms","hash":2870413366,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":42696704,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-09T02:00:06.372342Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2870413366,"revision":23764,"compact-revision":21641} {"level":"info","ts":"2026-09-09T02:04:54.680223Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"54232768b5552f01","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T02:04:54.686542Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-09T02:04:54.686676Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-09T02:05:06.258286Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26911} {"level":"info","ts":"2026-09-09T02:05:06.401854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26911,"took":"138.660893ms","hash":4002775139,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":31113216,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T02:05:06.401919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4002775139,"revision":26911,"compact-revision":23764}