{"level":"warn","ts":"2026-09-08T15:43:30.689547Z","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-08T15:43:30.689632Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.128: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.128:2380","--initial-cluster=instance=https://162.253.55.128:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.128:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.128: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-08T15:43:30.689701Z","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-08T15:43:30.689723Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.128:2380"]} {"level":"info","ts":"2026-09-08T15:43:30.689752Z","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-08T15:43:30.690885Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.128:2379"]} {"level":"info","ts":"2026-09-08T15:43:30.690977Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.128:2380"],"listen-peer-urls":["https://162.253.55.128:2380"],"advertise-client-urls":["https://162.253.55.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.128: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-08T15:43:30.69771Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.388032ms"} {"level":"info","ts":"2026-09-08T15:43:30.707454Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3e8b7bea2e3a32b","cluster-id":"ca619600f96ab468"} {"level":"info","ts":"2026-09-08T15:43:30.707564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T15:43:30.707592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became follower at term 0"} {"level":"info","ts":"2026-09-08T15:43:30.707609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3e8b7bea2e3a32b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T15:43:30.707626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became follower at term 1"} {"level":"info","ts":"2026-09-08T15:43:30.707671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b switched to configuration voters=(16422578070611010347)"} {"level":"warn","ts":"2026-09-08T15:43:30.713727Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T15:43:30.715216Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T15:43:30.716431Z","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-08T15:43:30.71859Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3e8b7bea2e3a32b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T15:43:30.718766Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3e8b7bea2e3a32b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T15:43:30.718889Z","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-08T15:43:30.718989Z","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-08T15:43:30.719029Z","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-08T15:43:30.721889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b switched to configuration voters=(16422578070611010347)"} {"level":"info","ts":"2026-09-08T15:43:30.722162Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ca619600f96ab468","local-member-id":"e3e8b7bea2e3a32b","added-peer-id":"e3e8b7bea2e3a32b","added-peer-peer-urls":["https://162.253.55.128:2380"]} {"level":"info","ts":"2026-09-08T15:43:30.722559Z","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-08T15:43:30.722758Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3e8b7bea2e3a32b","initial-advertise-peer-urls":["https://162.253.55.128:2380"],"listen-peer-urls":["https://162.253.55.128:2380"],"advertise-client-urls":["https://162.253.55.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T15:43:30.72279Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T15:43:30.722876Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.128:2380"} {"level":"info","ts":"2026-09-08T15:43:30.722883Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.128:2380"} {"level":"info","ts":"2026-09-08T15:43:30.908184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T15:43:30.908238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T15:43:30.908273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b received MsgPreVoteResp from e3e8b7bea2e3a32b at term 1"} {"level":"info","ts":"2026-09-08T15:43:30.9083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became candidate at term 2"} {"level":"info","ts":"2026-09-08T15:43:30.908316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b received MsgVoteResp from e3e8b7bea2e3a32b at term 2"} {"level":"info","ts":"2026-09-08T15:43:30.908331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became leader at term 2"} {"level":"info","ts":"2026-09-08T15:43:30.908347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3e8b7bea2e3a32b elected leader e3e8b7bea2e3a32b at term 2"} {"level":"info","ts":"2026-09-08T15:43:30.909622Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:43:30.912038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:43:30.912035Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3e8b7bea2e3a32b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.128:2379]}","request-path":"/0/members/e3e8b7bea2e3a32b/attributes","cluster-id":"ca619600f96ab468","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T15:43:30.912129Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:43:30.912474Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T15:43:30.912524Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T15:43:30.912694Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ca619600f96ab468","local-member-id":"e3e8b7bea2e3a32b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:43:30.912853Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:43:30.91289Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:43:30.914724Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T15:43:30.914983Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.128:2379"} {"level":"warn","ts":"2026-09-08T15:43:32.807087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.28095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-08T15:43:32.80732Z","caller":"traceutil/trace.go:171","msg":"trace[1337362912] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"155.635718ms","start":"2026-09-08T15:43:32.651643Z","end":"2026-09-08T15:43:32.807279Z","steps":["trace[1337362912] 'process raft request' (duration: 44.498849ms)","trace[1337362912] 'compare' (duration: 110.096081ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:43:32.807419Z","caller":"traceutil/trace.go:171","msg":"trace[1254489576] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"154.917879ms","start":"2026-09-08T15:43:32.652485Z","end":"2026-09-08T15:43:32.807403Z","steps":["trace[1254489576] 'process raft request' (duration: 154.7295ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:32.807574Z","caller":"traceutil/trace.go:171","msg":"trace[1982757337] linearizableReadLoop","detail":"{readStateIndex:46; appliedIndex:43; }","duration":"101.942874ms","start":"2026-09-08T15:43:32.705611Z","end":"2026-09-08T15:43:32.807554Z","steps":["trace[1982757337] 'read index received' (duration: 12.112038ms)","trace[1982757337] 'applied index is now lower than readState.Index' (duration: 89.829956ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:43:32.807682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.070673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-08T15:43:32.807711Z","caller":"traceutil/trace.go:171","msg":"trace[2057797022] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:42; }","duration":"102.107793ms","start":"2026-09-08T15:43:32.705595Z","end":"2026-09-08T15:43:32.807703Z","steps":["trace[2057797022] 'agreement among raft nodes before linearized reading' (duration: 102.022524ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:32.807744Z","caller":"traceutil/trace.go:171","msg":"trace[328554387] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"152.243157ms","start":"2026-09-08T15:43:32.655485Z","end":"2026-09-08T15:43:32.807728Z","steps":["trace[328554387] 'process raft request' (duration: 151.802139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:43:33.49157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.009508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-09-08T15:43:33.491759Z","caller":"traceutil/trace.go:171","msg":"trace[987556004] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"678.943896ms","start":"2026-09-08T15:43:32.812773Z","end":"2026-09-08T15:43:33.491717Z","steps":["trace[987556004] 'process raft request' (duration: 353.506329ms)","trace[987556004] 'compare' (duration: 324.858979ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:43:33.491989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:43:32.812757Z","time spent":"679.089217ms","remote":"127.0.0.1:52206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:43:33.492086Z","caller":"traceutil/trace.go:171","msg":"trace[16353192] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"666.465481ms","start":"2026-09-08T15:43:32.825424Z","end":"2026-09-08T15:43:33.491889Z","steps":["trace[16353192] 'process raft request' (duration: 666.387921ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:33.492369Z","caller":"traceutil/trace.go:171","msg":"trace[1306865700] linearizableReadLoop","detail":"{readStateIndex:48; appliedIndex:46; }","duration":"679.330066ms","start":"2026-09-08T15:43:32.813011Z","end":"2026-09-08T15:43:33.492341Z","steps":["trace[1306865700] 'read index received' (duration: 9.156965ms)","trace[1306865700] 'applied index is now lower than readState.Index' (duration: 670.153221ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:43:33.492418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:43:32.825408Z","time spent":"666.86103ms","remote":"127.0.0.1:52206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":796,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T15:43:33.492489Z","caller":"traceutil/trace.go:171","msg":"trace[1823267561] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"677.218583ms","start":"2026-09-08T15:43:32.815256Z","end":"2026-09-08T15:43:33.492475Z","steps":["trace[1823267561] 'process raft request' (duration: 676.405005ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:43:33.492594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.583115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-08T15:43:33.492593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:43:32.815245Z","time spent":"677.286093ms","remote":"127.0.0.1:52148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-08T15:43:33.492593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.233144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-08T15:43:33.492605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.333496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-08T15:43:33.492654Z","caller":"traceutil/trace.go:171","msg":"trace[1225023986] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:46; }","duration":"679.637125ms","start":"2026-09-08T15:43:32.81298Z","end":"2026-09-08T15:43:33.492617Z","steps":["trace[1225023986] 'agreement among raft nodes before linearized reading' (duration: 679.575805ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:33.492659Z","caller":"traceutil/trace.go:171","msg":"trace[1540612124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:46; }","duration":"118.407896ms","start":"2026-09-08T15:43:33.374238Z","end":"2026-09-08T15:43:33.492646Z","steps":["trace[1540612124] 'agreement among raft nodes before linearized reading' (duration: 118.289757ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:33.492663Z","caller":"traceutil/trace.go:171","msg":"trace[760586437] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:46; }","duration":"606.333384ms","start":"2026-09-08T15:43:32.886306Z","end":"2026-09-08T15:43:33.49264Z","steps":["trace[760586437] 'agreement among raft nodes before linearized reading' (duration: 606.193065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:43:33.492689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:43:32.81297Z","time spent":"679.711485ms","remote":"127.0.0.1:51862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" "} {"level":"warn","ts":"2026-09-08T15:43:33.492661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.923749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-08T15:43:33.49271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:43:32.886292Z","time spent":"606.405684ms","remote":"127.0.0.1:51912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-09-08T15:43:33.492741Z","caller":"traceutil/trace.go:171","msg":"trace[62284345] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:46; }","duration":"142.029199ms","start":"2026-09-08T15:43:33.350689Z","end":"2026-09-08T15:43:33.492718Z","steps":["trace[62284345] 'agreement among raft nodes before linearized reading' (duration: 141.911799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:43:33.492765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.598311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-08T15:43:33.492812Z","caller":"traceutil/trace.go:171","msg":"trace[499343613] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:46; }","duration":"151.682951ms","start":"2026-09-08T15:43:33.341116Z","end":"2026-09-08T15:43:33.492799Z","steps":["trace[499343613] 'agreement among raft nodes before linearized reading' (duration: 151.591791ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:33.749147Z","caller":"traceutil/trace.go:171","msg":"trace[1421568182] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"253.70276ms","start":"2026-09-08T15:43:33.495416Z","end":"2026-09-08T15:43:33.749119Z","steps":["trace[1421568182] 'process raft request' (duration: 159.660415ms)","trace[1421568182] 'compare' (duration: 93.889475ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:43:33.750935Z","caller":"traceutil/trace.go:171","msg":"trace[486529032] linearizableReadLoop","detail":"{readStateIndex:52; appliedIndex:50; }","duration":"254.635008ms","start":"2026-09-08T15:43:33.496234Z","end":"2026-09-08T15:43:33.750869Z","steps":["trace[486529032] 'read index received' (duration: 158.968937ms)","trace[486529032] 'applied index is now lower than readState.Index' (duration: 95.665051ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:43:33.751207Z","caller":"traceutil/trace.go:171","msg":"trace[314452745] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"254.131038ms","start":"2026-09-08T15:43:33.497052Z","end":"2026-09-08T15:43:33.751183Z","steps":["trace[314452745] 'process raft request' (duration: 253.732979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:43:33.751271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.82761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-08T15:43:33.751376Z","caller":"traceutil/trace.go:171","msg":"trace[2056115842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:51; }","duration":"253.96041ms","start":"2026-09-08T15:43:33.497395Z","end":"2026-09-08T15:43:33.751356Z","steps":["trace[2056115842] 'agreement among raft nodes before linearized reading' (duration: 253.82178ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:33.751535Z","caller":"traceutil/trace.go:171","msg":"trace[815376717] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"255.557837ms","start":"2026-09-08T15:43:33.495967Z","end":"2026-09-08T15:43:33.751524Z","steps":["trace[815376717] 'process raft request' (duration: 254.714358ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:33.751579Z","caller":"traceutil/trace.go:171","msg":"trace[1651286944] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"253.236591ms","start":"2026-09-08T15:43:33.498331Z","end":"2026-09-08T15:43:33.751567Z","steps":["trace[1651286944] 'process raft request' (duration: 252.496852ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:33.751725Z","caller":"traceutil/trace.go:171","msg":"trace[827063619] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"254.829738ms","start":"2026-09-08T15:43:33.496886Z","end":"2026-09-08T15:43:33.751716Z","steps":["trace[827063619] 'process raft request' (duration: 253.868179ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:43:33.751721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.481497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-08T15:43:33.75249Z","caller":"traceutil/trace.go:171","msg":"trace[799056262] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:51; }","duration":"256.278525ms","start":"2026-09-08T15:43:33.496194Z","end":"2026-09-08T15:43:33.752473Z","steps":["trace[799056262] 'agreement among raft nodes before linearized reading' (duration: 255.500017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:44:11.445533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.308003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:44:11.445752Z","caller":"traceutil/trace.go:171","msg":"trace[417400758] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"210.231877ms","start":"2026-09-08T15:44:11.235493Z","end":"2026-09-08T15:44:11.445724Z","steps":["trace[417400758] 'process raft request' (duration: 76.593528ms)","trace[417400758] 'compare' (duration: 133.17203ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:44:11.44604Z","caller":"traceutil/trace.go:171","msg":"trace[2014604846] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"197.978583ms","start":"2026-09-08T15:44:11.248049Z","end":"2026-09-08T15:44:11.446027Z","steps":["trace[2014604846] 'process raft request' (duration: 197.575064ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:44:11.446227Z","caller":"traceutil/trace.go:171","msg":"trace[4861553] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"198.062894ms","start":"2026-09-08T15:44:11.24815Z","end":"2026-09-08T15:44:11.446213Z","steps":["trace[4861553] 'process raft request' (duration: 197.986923ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:44:11.450887Z","caller":"traceutil/trace.go:171","msg":"trace[1499828095] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"186.883799ms","start":"2026-09-08T15:44:11.263985Z","end":"2026-09-08T15:44:11.450869Z","steps":["trace[1499828095] 'process raft request' (duration: 186.786047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:44:13.482355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.716538ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:44:13.482529Z","caller":"traceutil/trace.go:171","msg":"trace[755323150] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:573; }","duration":"124.900351ms","start":"2026-09-08T15:44:13.357607Z","end":"2026-09-08T15:44:13.482507Z","steps":["trace[755323150] 'range keys from in-memory index tree' (duration: 124.703307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:44:37.949686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.580598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:44:37.949791Z","caller":"traceutil/trace.go:171","msg":"trace[2087701010] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"316.829904ms","start":"2026-09-08T15:44:37.632945Z","end":"2026-09-08T15:44:37.949775Z","steps":["trace[2087701010] 'read index received' (duration: 83.055251ms)","trace[2087701010] 'applied index is now lower than readState.Index' (duration: 233.773143ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:44:37.949984Z","caller":"traceutil/trace.go:171","msg":"trace[1119181231] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"407.051654ms","start":"2026-09-08T15:44:37.542921Z","end":"2026-09-08T15:44:37.949973Z","steps":["trace[1119181231] 'process raft request' (duration: 173.097035ms)","trace[1119181231] 'compare' (duration: 233.139236ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:44:37.950039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:44:37.542893Z","time spent":"407.115626ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:44:37.950339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.108531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:44:37.950447Z","caller":"traceutil/trace.go:171","msg":"trace[1779295496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"116.270835ms","start":"2026-09-08T15:44:37.834144Z","end":"2026-09-08T15:44:37.950415Z","steps":["trace[1779295496] 'agreement among raft nodes before linearized reading' (duration: 116.057079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:44:37.950777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.649117ms","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-08T15:44:37.950854Z","caller":"traceutil/trace.go:171","msg":"trace[1666305766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:776; }","duration":"317.922335ms","start":"2026-09-08T15:44:37.632916Z","end":"2026-09-08T15:44:37.950838Z","steps":["trace[1666305766] 'agreement among raft nodes before linearized reading' (duration: 317.218326ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:44:37.950895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:44:37.632902Z","time spent":"317.979556ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-08T15:45:08.905991Z","caller":"traceutil/trace.go:171","msg":"trace[1382627653] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"106.993664ms","start":"2026-09-08T15:45:08.798962Z","end":"2026-09-08T15:45:08.905956Z","steps":["trace[1382627653] 'process raft request' (duration: 106.514688ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:51.241778Z","caller":"traceutil/trace.go:171","msg":"trace[1867000390] linearizableReadLoop","detail":"{readStateIndex:1815; appliedIndex:1814; }","duration":"121.883846ms","start":"2026-09-08T15:45:51.119867Z","end":"2026-09-08T15:45:51.24175Z","steps":["trace[1867000390] 'read index received' (duration: 121.662868ms)","trace[1867000390] 'applied index is now lower than readState.Index' (duration: 219.828µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:51.241935Z","caller":"traceutil/trace.go:171","msg":"trace[826303107] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"122.352686ms","start":"2026-09-08T15:45:51.119517Z","end":"2026-09-08T15:45:51.24187Z","steps":["trace[826303107] 'process raft request' (duration: 122.092116ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:51.242002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.113595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-08T15:45:51.242055Z","caller":"traceutil/trace.go:171","msg":"trace[147058503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1739; }","duration":"122.199389ms","start":"2026-09-08T15:45:51.119841Z","end":"2026-09-08T15:45:51.242041Z","steps":["trace[147058503] 'agreement among raft nodes before linearized reading' (duration: 122.033282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:01.070275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.037379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-09-08T15:46:01.07053Z","caller":"traceutil/trace.go:171","msg":"trace[207091193] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1863; }","duration":"402.360742ms","start":"2026-09-08T15:46:00.668121Z","end":"2026-09-08T15:46:01.070482Z","steps":["trace[207091193] 'range keys from in-memory index tree' (duration: 401.690785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:01.070679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:00.668103Z","time spent":"402.562421ms","remote":"127.0.0.1:51950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10692,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"info","ts":"2026-09-08T15:46:01.124889Z","caller":"traceutil/trace.go:171","msg":"trace[111751375] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1941; }","duration":"300.680258ms","start":"2026-09-08T15:46:00.824181Z","end":"2026-09-08T15:46:01.124861Z","steps":["trace[111751375] 'read index received' (duration: 300.508831ms)","trace[111751375] 'applied index is now lower than readState.Index' (duration: 169.827µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:01.12501Z","caller":"traceutil/trace.go:171","msg":"trace[164625462] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"420.446096ms","start":"2026-09-08T15:46:00.704535Z","end":"2026-09-08T15:46:01.124981Z","steps":["trace[164625462] 'process raft request' (duration: 420.170315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:01.12517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.971629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-09-08T15:46:01.125168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:00.704517Z","time spent":"420.564421ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:01.125224Z","caller":"traceutil/trace.go:171","msg":"trace[1622928368] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1864; }","duration":"301.059943ms","start":"2026-09-08T15:46:00.824139Z","end":"2026-09-08T15:46:01.125199Z","steps":["trace[1622928368] 'agreement among raft nodes before linearized reading' (duration: 300.850235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:01.125461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:00.824122Z","time spent":"301.124405ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-08T15:46:01.18925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.42712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:01.189333Z","caller":"traceutil/trace.go:171","msg":"trace[1405028594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1866; }","duration":"355.537403ms","start":"2026-09-08T15:46:00.833773Z","end":"2026-09-08T15:46:01.189311Z","steps":["trace[1405028594] 'agreement among raft nodes before linearized reading' (duration: 355.402148ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:01.189441Z","caller":"traceutil/trace.go:171","msg":"trace[2072173524] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"345.245979ms","start":"2026-09-08T15:46:00.844152Z","end":"2026-09-08T15:46:01.189398Z","steps":["trace[2072173524] 'process raft request' (duration: 332.436631ms)","trace[2072173524] 'compare' (duration: 12.306918ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:01.189522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:00.833763Z","time spent":"355.672729ms","remote":"127.0.0.1:51794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-08T15:46:01.189645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:00.844136Z","time spent":"345.408686ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:01.189356Z","caller":"traceutil/trace.go:171","msg":"trace[818857796] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"109.522232ms","start":"2026-09-08T15:46:01.079798Z","end":"2026-09-08T15:46:01.18932Z","steps":["trace[818857796] 'process raft request' (duration: 109.247321ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:01.274007Z","caller":"traceutil/trace.go:171","msg":"trace[1367308775] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"145.063327ms","start":"2026-09-08T15:46:01.128922Z","end":"2026-09-08T15:46:01.273985Z","steps":["trace[1367308775] 'process raft request' (duration: 144.920282ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:01.336375Z","caller":"traceutil/trace.go:171","msg":"trace[2088932200] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"140.430111ms","start":"2026-09-08T15:46:01.195922Z","end":"2026-09-08T15:46:01.336352Z","steps":["trace[2088932200] 'process raft request' (duration: 96.773488ms)","trace[2088932200] 'compare' (duration: 43.505727ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:01.336591Z","caller":"traceutil/trace.go:171","msg":"trace[904061572] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"140.15406ms","start":"2026-09-08T15:46:01.196413Z","end":"2026-09-08T15:46:01.336551Z","steps":["trace[904061572] 'process raft request' (duration: 139.905369ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:01.336796Z","caller":"traceutil/trace.go:171","msg":"trace[18186336] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"140.361689ms","start":"2026-09-08T15:46:01.196415Z","end":"2026-09-08T15:46:01.336777Z","steps":["trace[18186336] 'process raft request' (duration: 140.074526ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:01.336958Z","caller":"traceutil/trace.go:171","msg":"trace[199471906] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"138.827917ms","start":"2026-09-08T15:46:01.198109Z","end":"2026-09-08T15:46:01.336937Z","steps":["trace[199471906] 'process raft request' (duration: 138.564736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:01.337001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.316337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b5f7b7e-12da-4b82-be8b-6a5bbd7639e2\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-08T15:46:01.33706Z","caller":"traceutil/trace.go:171","msg":"trace[59282445] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3b5f7b7e-12da-4b82-be8b-6a5bbd7639e2; range_end:; response_count:1; response_revision:1871; }","duration":"143.411582ms","start":"2026-09-08T15:46:01.193631Z","end":"2026-09-08T15:46:01.337042Z","steps":["trace[59282445] 'agreement among raft nodes before linearized reading' (duration: 143.169533ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:01.633458Z","caller":"traceutil/trace.go:171","msg":"trace[232093047] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"220.244783ms","start":"2026-09-08T15:46:01.413193Z","end":"2026-09-08T15:46:01.633437Z","steps":["trace[232093047] 'read index received' (duration: 201.09657ms)","trace[232093047] 'applied index is now lower than readState.Index' (duration: 19.147213ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:01.633964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.828213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-08T15:46:01.633973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.735383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-08T15:46:01.6341Z","caller":"traceutil/trace.go:171","msg":"trace[703611638] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1873; }","duration":"220.945072ms","start":"2026-09-08T15:46:01.413128Z","end":"2026-09-08T15:46:01.634074Z","steps":["trace[703611638] 'agreement among raft nodes before linearized reading' (duration: 220.506855ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:01.633987Z","caller":"traceutil/trace.go:171","msg":"trace[1917689127] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"287.483519ms","start":"2026-09-08T15:46:01.346471Z","end":"2026-09-08T15:46:01.633955Z","steps":["trace[1917689127] 'process raft request' (duration: 267.899969ms)","trace[1917689127] 'compare' (duration: 18.958714ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:01.63412Z","caller":"traceutil/trace.go:171","msg":"trace[1855924048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1873; }","duration":"104.986939ms","start":"2026-09-08T15:46:01.529066Z","end":"2026-09-08T15:46:01.634053Z","steps":["trace[1855924048] 'agreement among raft nodes before linearized reading' (duration: 104.651466ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:13.422133Z","caller":"traceutil/trace.go:171","msg":"trace[1225025989] linearizableReadLoop","detail":"{readStateIndex:2052; appliedIndex:2051; }","duration":"146.195019ms","start":"2026-09-08T15:46:13.275915Z","end":"2026-09-08T15:46:13.42211Z","steps":["trace[1225025989] 'read index received' (duration: 145.951428ms)","trace[1225025989] 'applied index is now lower than readState.Index' (duration: 242.621µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:13.422211Z","caller":"traceutil/trace.go:171","msg":"trace[34441339] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"185.449405ms","start":"2026-09-08T15:46:13.236732Z","end":"2026-09-08T15:46:13.422181Z","steps":["trace[34441339] 'process raft request' (duration: 185.222006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:13.422347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.424208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-08T15:46:13.422394Z","caller":"traceutil/trace.go:171","msg":"trace[438718496] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1971; }","duration":"146.518692ms","start":"2026-09-08T15:46:13.27586Z","end":"2026-09-08T15:46:13.422378Z","steps":["trace[438718496] 'agreement among raft nodes before linearized reading' (duration: 146.366756ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:17.435284Z","caller":"traceutil/trace.go:171","msg":"trace[715755557] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"245.071185ms","start":"2026-09-08T15:46:17.190193Z","end":"2026-09-08T15:46:17.435265Z","steps":["trace[715755557] 'process raft request' (duration: 244.93652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:39.775128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.563454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:39.775206Z","caller":"traceutil/trace.go:171","msg":"trace[852420305] linearizableReadLoop","detail":"{readStateIndex:2214; appliedIndex:2213; }","duration":"128.107826ms","start":"2026-09-08T15:46:39.647086Z","end":"2026-09-08T15:46:39.775193Z","steps":["trace[852420305] 'read index received' (duration: 52.662µs)","trace[852420305] 'applied index is now lower than readState.Index' (duration: 128.054224ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:39.775239Z","caller":"traceutil/trace.go:171","msg":"trace[687996662] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"157.309396ms","start":"2026-09-08T15:46:39.617896Z","end":"2026-09-08T15:46:39.775206Z","steps":["trace[687996662] 'compare' (duration: 148.489741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:39.775314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.227561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-08T15:46:39.77533Z","caller":"traceutil/trace.go:171","msg":"trace[1066775978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2127; }","duration":"128.273783ms","start":"2026-09-08T15:46:39.647051Z","end":"2026-09-08T15:46:39.775325Z","steps":["trace[1066775978] 'agreement among raft nodes before linearized reading' (duration: 128.173549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:41.095849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.362299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:41.095951Z","caller":"traceutil/trace.go:171","msg":"trace[1442116600] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2219; }","duration":"261.452758ms","start":"2026-09-08T15:46:40.834478Z","end":"2026-09-08T15:46:41.095931Z","steps":["trace[1442116600] 'read index received' (duration: 54.928322ms)","trace[1442116600] 'applied index is now lower than readState.Index' (duration: 206.522905ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:41.095991Z","caller":"traceutil/trace.go:171","msg":"trace[923684152] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"277.558642ms","start":"2026-09-08T15:46:40.818414Z","end":"2026-09-08T15:46:41.095973Z","steps":["trace[923684152] 'process raft request' (duration: 70.982074ms)","trace[923684152] 'compare' (duration: 206.156291ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:41.096182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.703879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:41.096228Z","caller":"traceutil/trace.go:171","msg":"trace[621836655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2132; }","duration":"261.787922ms","start":"2026-09-08T15:46:40.834427Z","end":"2026-09-08T15:46:41.096214Z","steps":["trace[621836655] 'agreement among raft nodes before linearized reading' (duration: 261.706529ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:41.11804Z","caller":"traceutil/trace.go:171","msg":"trace[1254836213] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"105.764357ms","start":"2026-09-08T15:46:41.012256Z","end":"2026-09-08T15:46:41.118021Z","steps":["trace[1254836213] 'process raft request' (duration: 105.623241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:41.118345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.016417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T15:46:41.118468Z","caller":"traceutil/trace.go:171","msg":"trace[900535985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2133; }","duration":"187.215206ms","start":"2026-09-08T15:46:40.931231Z","end":"2026-09-08T15:46:41.118446Z","steps":["trace[900535985] 'agreement among raft nodes before linearized reading' (duration: 186.806288ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:41.903186Z","caller":"traceutil/trace.go:171","msg":"trace[88078513] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"780.473169ms","start":"2026-09-08T15:46:41.122698Z","end":"2026-09-08T15:46:41.903171Z","steps":["trace[88078513] 'process raft request' (duration: 780.375945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:41.90331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:41.122675Z","time spent":"780.562883ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:42.15187Z","caller":"traceutil/trace.go:171","msg":"trace[1329087745] linearizableReadLoop","detail":"{readStateIndex:2225; appliedIndex:2222; }","duration":"526.127912ms","start":"2026-09-08T15:46:41.625719Z","end":"2026-09-08T15:46:42.151847Z","steps":["trace[1329087745] 'read index received' (duration: 277.438624ms)","trace[1329087745] 'applied index is now lower than readState.Index' (duration: 248.688338ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:42.152165Z","caller":"traceutil/trace.go:171","msg":"trace[1239621654] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"831.62621ms","start":"2026-09-08T15:46:41.320525Z","end":"2026-09-08T15:46:42.152152Z","steps":["trace[1239621654] 'process raft request' (duration: 831.103648ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:42.15252Z","caller":"traceutil/trace.go:171","msg":"trace[262030877] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"624.767175ms","start":"2026-09-08T15:46:41.527739Z","end":"2026-09-08T15:46:42.152507Z","steps":["trace[262030877] 'process raft request' (duration: 624.075676ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:42.152703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:41.527712Z","time spent":"624.843389ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:46:42.152836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:41.320499Z","time spent":"831.755496ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:46:42.153183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.455678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-08T15:46:42.153226Z","caller":"traceutil/trace.go:171","msg":"trace[102962596] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2137; }","duration":"527.539102ms","start":"2026-09-08T15:46:41.625675Z","end":"2026-09-08T15:46:42.153214Z","steps":["trace[102962596] 'agreement among raft nodes before linearized reading' (duration: 527.397626ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:42.153684Z","caller":"traceutil/trace.go:171","msg":"trace[364443146] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"628.274205ms","start":"2026-09-08T15:46:41.525398Z","end":"2026-09-08T15:46:42.153672Z","steps":["trace[364443146] 'process raft request' (duration: 626.378044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:42.153963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:41.625656Z","time spent":"527.592284ms","remote":"127.0.0.1:49988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-08T15:46:42.153757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:41.52537Z","time spent":"628.352558ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:42.335273Z","caller":"traceutil/trace.go:171","msg":"trace[2095278767] linearizableReadLoop","detail":"{readStateIndex:2226; appliedIndex:2225; }","duration":"183.292279ms","start":"2026-09-08T15:46:42.151941Z","end":"2026-09-08T15:46:42.335234Z","steps":["trace[2095278767] 'read index received' (duration: 178.767497ms)","trace[2095278767] 'applied index is now lower than readState.Index' (duration: 4.523232ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:42.335439Z","caller":"traceutil/trace.go:171","msg":"trace[983653278] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"290.498671ms","start":"2026-09-08T15:46:42.044904Z","end":"2026-09-08T15:46:42.335403Z","steps":["trace[983653278] 'process raft request' (duration: 285.862253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:42.335579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.904788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-09-08T15:46:42.335653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.845405ms","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-08T15:46:42.335721Z","caller":"traceutil/trace.go:171","msg":"trace[822633175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2138; }","duration":"468.934429ms","start":"2026-09-08T15:46:41.866767Z","end":"2026-09-08T15:46:42.335702Z","steps":["trace[822633175] 'agreement among raft nodes before linearized reading' (duration: 468.50765ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:42.33566Z","caller":"traceutil/trace.go:171","msg":"trace[2105053168] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2138; }","duration":"557.032413ms","start":"2026-09-08T15:46:41.778608Z","end":"2026-09-08T15:46:42.33564Z","steps":["trace[2105053168] 'agreement among raft nodes before linearized reading' (duration: 556.855126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:42.335767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:41.866758Z","time spent":"468.996931ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-08T15:46:42.335785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:41.778589Z","time spent":"557.18112ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-08T15:46:42.336069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.985383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-08T15:46:42.336136Z","caller":"traceutil/trace.go:171","msg":"trace[2060632148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2138; }","duration":"474.081506ms","start":"2026-09-08T15:46:41.862039Z","end":"2026-09-08T15:46:42.33612Z","steps":["trace[2060632148] 'agreement among raft nodes before linearized reading' (duration: 473.257821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:42.336173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:41.862023Z","time spent":"474.139619ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-08T15:48:00.914261Z","caller":"traceutil/trace.go:171","msg":"trace[2004884810] linearizableReadLoop","detail":"{readStateIndex:2699; appliedIndex:2698; }","duration":"130.468676ms","start":"2026-09-08T15:48:00.783746Z","end":"2026-09-08T15:48:00.914215Z","steps":["trace[2004884810] 'read index received' (duration: 129.807221ms)","trace[2004884810] 'applied index is now lower than readState.Index' (duration: 660.625µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:00.914339Z","caller":"traceutil/trace.go:171","msg":"trace[1111917952] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"227.340888ms","start":"2026-09-08T15:48:00.686951Z","end":"2026-09-08T15:48:00.914291Z","steps":["trace[1111917952] 'process raft request' (duration: 226.590983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:00.914635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.848498ms","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-08T15:48:00.914749Z","caller":"traceutil/trace.go:171","msg":"trace[2100580705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2593; }","duration":"130.940019ms","start":"2026-09-08T15:48:00.783717Z","end":"2026-09-08T15:48:00.914657Z","steps":["trace[2100580705] 'agreement among raft nodes before linearized reading' (duration: 130.766788ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:01.115547Z","caller":"traceutil/trace.go:171","msg":"trace[1215896064] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"197.212766ms","start":"2026-09-08T15:48:00.918305Z","end":"2026-09-08T15:48:01.115518Z","steps":["trace[1215896064] 'process raft request' (duration: 197.016644ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:01.115623Z","caller":"traceutil/trace.go:171","msg":"trace[939104565] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"196.719594ms","start":"2026-09-08T15:48:00.91888Z","end":"2026-09-08T15:48:01.1156Z","steps":["trace[939104565] 'process raft request' (duration: 196.515852ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:01.115641Z","caller":"traceutil/trace.go:171","msg":"trace[335124003] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"105.652914ms","start":"2026-09-08T15:48:01.009973Z","end":"2026-09-08T15:48:01.115626Z","steps":["trace[335124003] 'process raft request' (duration: 105.522503ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:01.115761Z","caller":"traceutil/trace.go:171","msg":"trace[2056059657] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"106.055986ms","start":"2026-09-08T15:48:01.009699Z","end":"2026-09-08T15:48:01.115755Z","steps":["trace[2056059657] 'process raft request' (duration: 105.754404ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:01.115849Z","caller":"traceutil/trace.go:171","msg":"trace[1187357892] transaction","detail":"{read_only:false; response_revision:2596; number_of_response:1; }","duration":"197.618608ms","start":"2026-09-08T15:48:00.918214Z","end":"2026-09-08T15:48:01.115833Z","steps":["trace[1187357892] 'process raft request' (duration: 102.238129ms)","trace[1187357892] 'compare' (duration: 94.672684ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:11.335604Z","caller":"traceutil/trace.go:171","msg":"trace[1845803794] transaction","detail":"{read_only:false; response_revision:2678; number_of_response:1; }","duration":"106.361153ms","start":"2026-09-08T15:48:11.229211Z","end":"2026-09-08T15:48:11.335572Z","steps":["trace[1845803794] 'process raft request' (duration: 106.141131ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:13.553674Z","caller":"traceutil/trace.go:171","msg":"trace[1563446350] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"151.238658ms","start":"2026-09-08T15:48:13.402405Z","end":"2026-09-08T15:48:13.553643Z","steps":["trace[1563446350] 'process raft request' (duration: 151.050096ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:44.600053Z","caller":"traceutil/trace.go:171","msg":"trace[513634063] transaction","detail":"{read_only:false; response_revision:2893; number_of_response:1; }","duration":"127.990591ms","start":"2026-09-08T15:48:44.472024Z","end":"2026-09-08T15:48:44.600015Z","steps":["trace[513634063] 'process raft request' (duration: 127.835849ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:49.946605Z","caller":"traceutil/trace.go:171","msg":"trace[870869714] linearizableReadLoop","detail":"{readStateIndex:3040; appliedIndex:3039; }","duration":"144.745577ms","start":"2026-09-08T15:48:49.801839Z","end":"2026-09-08T15:48:49.946584Z","steps":["trace[870869714] 'read index received' (duration: 144.550714ms)","trace[870869714] 'applied index is now lower than readState.Index' (duration: 194.033µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:49.946954Z","caller":"traceutil/trace.go:171","msg":"trace[81935859] transaction","detail":"{read_only:false; response_revision:2925; number_of_response:1; }","duration":"163.456247ms","start":"2026-09-08T15:48:49.783486Z","end":"2026-09-08T15:48:49.946942Z","steps":["trace[81935859] 'process raft request' (duration: 162.972009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:49.947116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.400352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:48:49.947168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.301026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T15:48:49.947237Z","caller":"traceutil/trace.go:171","msg":"trace[2047167918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2925; }","duration":"114.581205ms","start":"2026-09-08T15:48:49.832626Z","end":"2026-09-08T15:48:49.947207Z","steps":["trace[2047167918] 'agreement among raft nodes before linearized reading' (duration: 114.341871ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:49.947256Z","caller":"traceutil/trace.go:171","msg":"trace[395760693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2925; }","duration":"145.407937ms","start":"2026-09-08T15:48:49.801809Z","end":"2026-09-08T15:48:49.947217Z","steps":["trace[395760693] 'agreement among raft nodes before linearized reading' (duration: 145.209234ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:50.143498Z","caller":"traceutil/trace.go:171","msg":"trace[686255669] transaction","detail":"{read_only:false; response_revision:2926; number_of_response:1; }","duration":"192.656977ms","start":"2026-09-08T15:48:49.95081Z","end":"2026-09-08T15:48:50.143466Z","steps":["trace[686255669] 'process raft request' (duration: 154.434805ms)","trace[686255669] 'compare' (duration: 37.935777ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:00.832952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.039506ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-08T15:49:00.833059Z","caller":"traceutil/trace.go:171","msg":"trace[903237817] linearizableReadLoop","detail":"{readStateIndex:3097; appliedIndex:3096; }","duration":"140.907086ms","start":"2026-09-08T15:49:00.692136Z","end":"2026-09-08T15:49:00.833043Z","steps":["trace[903237817] 'read index received' (duration: 41.831µs)","trace[903237817] 'applied index is now lower than readState.Index' (duration: 140.863405ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:00.833308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.185741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T15:49:00.833348Z","caller":"traceutil/trace.go:171","msg":"trace[807370218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2979; }","duration":"141.271912ms","start":"2026-09-08T15:49:00.692066Z","end":"2026-09-08T15:49:00.833338Z","steps":["trace[807370218] 'agreement among raft nodes before linearized reading' (duration: 141.021838ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:24.005452Z","caller":"traceutil/trace.go:171","msg":"trace[584812345] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"231.288118ms","start":"2026-09-08T15:53:23.774138Z","end":"2026-09-08T15:53:24.005427Z","steps":["trace[584812345] 'process raft request' (duration: 231.149473ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:24.04622Z","caller":"traceutil/trace.go:171","msg":"trace[2133396530] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"236.034034ms","start":"2026-09-08T15:53:23.810158Z","end":"2026-09-08T15:53:24.046192Z","steps":["trace[2133396530] 'process raft request' (duration: 234.290229ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:24.421675Z","caller":"traceutil/trace.go:171","msg":"trace[1780817369] linearizableReadLoop","detail":"{readStateIndex:5092; appliedIndex:5090; }","duration":"105.34258ms","start":"2026-09-08T15:53:24.316303Z","end":"2026-09-08T15:53:24.421646Z","steps":["trace[1780817369] 'read index received' (duration: 35.118557ms)","trace[1780817369] 'applied index is now lower than readState.Index' (duration: 70.222733ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:53:24.422089Z","caller":"traceutil/trace.go:171","msg":"trace[138758790] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"105.936021ms","start":"2026-09-08T15:53:24.316122Z","end":"2026-09-08T15:53:24.422058Z","steps":["trace[138758790] 'process raft request' (duration: 96.401952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:24.422167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.499948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-08T15:53:24.422206Z","caller":"traceutil/trace.go:171","msg":"trace[1644581642] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4896; }","duration":"113.586811ms","start":"2026-09-08T15:53:24.308608Z","end":"2026-09-08T15:53:24.422195Z","steps":["trace[1644581642] 'agreement among raft nodes before linearized reading' (duration: 113.442906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:24.422145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.535717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:1 size:232"} {"level":"info","ts":"2026-09-08T15:53:24.422416Z","caller":"traceutil/trace.go:171","msg":"trace[324436204] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:1; response_revision:4896; }","duration":"102.783646ms","start":"2026-09-08T15:53:24.319537Z","end":"2026-09-08T15:53:24.42232Z","steps":["trace[324436204] 'agreement among raft nodes before linearized reading' (duration: 102.490925ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:24.779245Z","caller":"traceutil/trace.go:171","msg":"trace[664965167] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"118.576444ms","start":"2026-09-08T15:53:24.660622Z","end":"2026-09-08T15:53:24.779199Z","steps":["trace[664965167] 'process raft request' (duration: 118.308934ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:31.083085Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2824} {"level":"info","ts":"2026-09-08T15:53:31.205274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2824,"took":"120.106386ms","hash":4151739450,"current-db-size-bytes":51073024,"current-db-size":"51 MB","current-db-size-in-use-bytes":26361856,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-08T15:53:31.205785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4151739450,"revision":2824,"compact-revision":-1} {"level":"info","ts":"2026-09-08T15:53:32.213976Z","caller":"traceutil/trace.go:171","msg":"trace[1145377077] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"117.274155ms","start":"2026-09-08T15:53:32.096666Z","end":"2026-09-08T15:53:32.21394Z","steps":["trace[1145377077] 'process raft request' (duration: 73.225414ms)","trace[1145377077] 'compare' (duration: 43.632185ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:53:35.883399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.691269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T15:53:35.883488Z","caller":"traceutil/trace.go:171","msg":"trace[1287451359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5179; }","duration":"186.844456ms","start":"2026-09-08T15:53:35.696624Z","end":"2026-09-08T15:53:35.883468Z","steps":["trace[1287451359] 'range keys from in-memory index tree' (duration: 186.481042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:35.883517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.277018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aebcf4ec-dff6-4282-8156-cb22ad47f8d6\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-08T15:53:35.883585Z","caller":"traceutil/trace.go:171","msg":"trace[410059894] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aebcf4ec-dff6-4282-8156-cb22ad47f8d6; range_end:; response_count:1; response_revision:5179; }","duration":"179.35531ms","start":"2026-09-08T15:53:35.704213Z","end":"2026-09-08T15:53:35.883568Z","steps":["trace[410059894] 'range keys from in-memory index tree' (duration: 179.136313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:50.731433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.528919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:53:50.73152Z","caller":"traceutil/trace.go:171","msg":"trace[996216674] transaction","detail":"{read_only:false; response_revision:5367; number_of_response:1; }","duration":"627.321667ms","start":"2026-09-08T15:53:50.104182Z","end":"2026-09-08T15:53:50.731504Z","steps":["trace[996216674] 'process raft request' (duration: 185.671452ms)","trace[996216674] 'compare' (duration: 441.043432ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:53:50.731568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:53:50.104154Z","time spent":"627.39181ms","remote":"127.0.0.1:51858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T15:53:50.780099Z","caller":"traceutil/trace.go:171","msg":"trace[1492342729] transaction","detail":"{read_only:false; response_revision:5368; number_of_response:1; }","duration":"428.400843ms","start":"2026-09-08T15:53:50.351642Z","end":"2026-09-08T15:53:50.780043Z","steps":["trace[1492342729] 'process raft request' (duration: 428.223486ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:50.78029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:53:50.351619Z","time spent":"428.565519ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:53:50.785011Z","caller":"traceutil/trace.go:171","msg":"trace[1938710497] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"427.260601ms","start":"2026-09-08T15:53:50.357727Z","end":"2026-09-08T15:53:50.784987Z","steps":["trace[1938710497] 'process raft request' (duration: 427.092654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:50.785221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:53:50.357712Z","time spent":"427.395096ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:53:50.785407Z","caller":"traceutil/trace.go:171","msg":"trace[2125284251] transaction","detail":"{read_only:false; response_revision:5370; number_of_response:1; }","duration":"427.267751ms","start":"2026-09-08T15:53:50.358115Z","end":"2026-09-08T15:53:50.785383Z","steps":["trace[2125284251] 'process raft request' (duration: 426.819505ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:50.785726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:53:50.358099Z","time spent":"427.523601ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:53:50.797259Z","caller":"traceutil/trace.go:171","msg":"trace[1583485279] linearizableReadLoop","detail":"{readStateIndex:5581; appliedIndex:5578; }","duration":"438.947964ms","start":"2026-09-08T15:53:50.358279Z","end":"2026-09-08T15:53:50.797227Z","steps":["trace[1583485279] 'read index received' (duration: 421.683674ms)","trace[1583485279] 'applied index is now lower than readState.Index' (duration: 17.26257ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:53:50.797594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.285597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:53:50.797672Z","caller":"traceutil/trace.go:171","msg":"trace[371558459] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:5370; }","duration":"439.407981ms","start":"2026-09-08T15:53:50.358248Z","end":"2026-09-08T15:53:50.797656Z","steps":["trace[371558459] 'agreement among raft nodes before linearized reading' (duration: 439.269466ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:50.797727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:53:50.358236Z","time spent":"439.476644ms","remote":"127.0.0.1:54906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-09-08T15:53:50.79774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.146657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:53:50.797829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.167295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-55b9c87444-w9t8b\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-09-08T15:53:50.797837Z","caller":"traceutil/trace.go:171","msg":"trace[1571231468] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5370; }","duration":"392.351914ms","start":"2026-09-08T15:53:50.405471Z","end":"2026-09-08T15:53:50.797822Z","steps":["trace[1571231468] 'agreement among raft nodes before linearized reading' (duration: 392.168648ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:50.79788Z","caller":"traceutil/trace.go:171","msg":"trace[1251289595] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-55b9c87444-w9t8b; range_end:; response_count:1; response_revision:5370; }","duration":"379.261828ms","start":"2026-09-08T15:53:50.418604Z","end":"2026-09-08T15:53:50.797865Z","steps":["trace[1251289595] 'agreement among raft nodes before linearized reading' (duration: 379.103022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:50.797916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:53:50.418594Z","time spent":"379.315351ms","remote":"127.0.0.1:51950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11872,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-55b9c87444-w9t8b\" "} {"level":"warn","ts":"2026-09-08T15:53:50.797925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:53:50.405453Z","time spent":"392.463629ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-09-08T15:53:50.798158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.912434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T15:53:50.798286Z","caller":"traceutil/trace.go:171","msg":"trace[334694950] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5370; }","duration":"390.012257ms","start":"2026-09-08T15:53:50.408195Z","end":"2026-09-08T15:53:50.798208Z","steps":["trace[334694950] 'agreement among raft nodes before linearized reading' (duration: 389.613843ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:50.798371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:53:50.408186Z","time spent":"390.136292ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-08T15:57:41.407888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.239047ms","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-08T15:57:41.407955Z","caller":"traceutil/trace.go:171","msg":"trace[1475694795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7033; }","duration":"154.360369ms","start":"2026-09-08T15:57:41.253579Z","end":"2026-09-08T15:57:41.407939Z","steps":["trace[1475694795] 'range keys from in-memory index tree' (duration: 154.044944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:41.989137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.633823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:57:41.989245Z","caller":"traceutil/trace.go:171","msg":"trace[2061433960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7035; }","duration":"154.791465ms","start":"2026-09-08T15:57:41.834427Z","end":"2026-09-08T15:57:41.989219Z","steps":["trace[2061433960] 'range keys from in-memory index tree' (duration: 154.551122ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:41.989383Z","caller":"traceutil/trace.go:171","msg":"trace[1222289720] transaction","detail":"{read_only:false; response_revision:7036; number_of_response:1; }","duration":"125.169001ms","start":"2026-09-08T15:57:41.864184Z","end":"2026-09-08T15:57:41.989353Z","steps":["trace[1222289720] 'process raft request' (duration: 65.336582ms)","trace[1222289720] 'compare' (duration: 59.710377ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:57:42.05231Z","caller":"traceutil/trace.go:171","msg":"trace[264285397] transaction","detail":"{read_only:false; response_revision:7037; number_of_response:1; }","duration":"186.813939ms","start":"2026-09-08T15:57:41.865473Z","end":"2026-09-08T15:57:42.052287Z","steps":["trace[264285397] 'process raft request' (duration: 186.581726ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:42.052923Z","caller":"traceutil/trace.go:171","msg":"trace[458575375] transaction","detail":"{read_only:false; response_revision:7038; number_of_response:1; }","duration":"170.348983ms","start":"2026-09-08T15:57:41.882559Z","end":"2026-09-08T15:57:42.052908Z","steps":["trace[458575375] 'process raft request' (duration: 169.672412ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:58.556988Z","caller":"traceutil/trace.go:171","msg":"trace[2105837576] transaction","detail":"{read_only:false; response_revision:7176; number_of_response:1; }","duration":"105.171127ms","start":"2026-09-08T15:57:58.451784Z","end":"2026-09-08T15:57:58.556955Z","steps":["trace[2105837576] 'process raft request' (duration: 90.216113ms)","trace[2105837576] 'compare' (duration: 13.817846ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:31.092595Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5094} {"level":"info","ts":"2026-09-08T15:58:31.176767Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5094,"took":"82.528908ms","hash":3753829814,"current-db-size-bytes":51073024,"current-db-size":"51 MB","current-db-size-in-use-bytes":19443712,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-08T15:58:31.176831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3753829814,"revision":5094,"compact-revision":2824}