{"level":"warn","ts":"2026-09-15T11:18:27.105219Z","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-15T11:18:27.105324Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.206: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.206:2380","--initial-cluster=instance=https://162.253.55.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.206: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-15T11:18:27.10541Z","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-15T11:18:27.105425Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-09-15T11:18:27.105443Z","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-15T11:18:27.106349Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"]} {"level":"info","ts":"2026-09-15T11:18:27.10644Z","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.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.206: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-15T11:18:27.111289Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.590412ms"} {"level":"info","ts":"2026-09-15T11:18:27.117246Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cfc9fec265e3384a","cluster-id":"9866fe2b0d49ed88"} {"level":"info","ts":"2026-09-15T11:18:27.117305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=()"} {"level":"info","ts":"2026-09-15T11:18:27.117321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 0"} {"level":"info","ts":"2026-09-15T11:18:27.117332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cfc9fec265e3384a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-15T11:18:27.11734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 1"} {"level":"info","ts":"2026-09-15T11:18:27.117374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"warn","ts":"2026-09-15T11:18:27.124573Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-15T11:18:27.126004Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-15T11:18:27.127442Z","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-15T11:18:27.129263Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cfc9fec265e3384a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-15T11:18:27.129518Z","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-15T11:18:27.129614Z","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-15T11:18:27.129625Z","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-15T11:18:27.130819Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cfc9fec265e3384a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-15T11:18:27.131525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"info","ts":"2026-09-15T11:18:27.131805Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","added-peer-id":"cfc9fec265e3384a","added-peer-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-09-15T11:18:27.132952Z","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-15T11:18:27.133203Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-09-15T11:18:27.13322Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-09-15T11:18:27.133725Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cfc9fec265e3384a","initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-15T11:18:27.133747Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-15T11:18:27.717592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a is starting a new election at term 1"} {"level":"info","ts":"2026-09-15T11:18:27.717653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-15T11:18:27.71769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgPreVoteResp from cfc9fec265e3384a at term 1"} {"level":"info","ts":"2026-09-15T11:18:27.717709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became candidate at term 2"} {"level":"info","ts":"2026-09-15T11:18:27.717728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgVoteResp from cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-09-15T11:18:27.717753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became leader at term 2"} {"level":"info","ts":"2026-09-15T11:18:27.717792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cfc9fec265e3384a elected leader cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-09-15T11:18:27.720231Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cfc9fec265e3384a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.206:2379]}","request-path":"/0/members/cfc9fec265e3384a/attributes","cluster-id":"9866fe2b0d49ed88","publish-timeout":"7s"} {"level":"info","ts":"2026-09-15T11:18:27.720277Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T11:18:27.720477Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T11:18:27.720804Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T11:18:27.720861Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-15T11:18:27.720888Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-15T11:18:27.721724Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T11:18:27.721837Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T11:18:27.721872Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T11:18:27.725447Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.206:2379"} {"level":"info","ts":"2026-09-15T11:18:27.726953Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-15T11:18:31.118404Z","caller":"traceutil/trace.go:171","msg":"trace[220062013] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"147.704434ms","start":"2026-09-15T11:18:30.970665Z","end":"2026-09-15T11:18:31.118369Z","steps":["trace[220062013] 'process raft request' (duration: 129.752005ms)","trace[220062013] 'compare' (duration: 17.632246ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:18:31.868064Z","caller":"traceutil/trace.go:171","msg":"trace[883238617] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"165.616342ms","start":"2026-09-15T11:18:31.702416Z","end":"2026-09-15T11:18:31.868033Z","steps":["trace[883238617] 'process raft request' (duration: 66.658171ms)","trace[883238617] 'compare' (duration: 98.756629ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:18:31.874693Z","caller":"traceutil/trace.go:171","msg":"trace[1708797008] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"151.739125ms","start":"2026-09-15T11:18:31.722921Z","end":"2026-09-15T11:18:31.87466Z","steps":["trace[1708797008] 'process raft request' (duration: 151.483242ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:31.874761Z","caller":"traceutil/trace.go:171","msg":"trace[670432057] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"114.303427ms","start":"2026-09-15T11:18:31.76044Z","end":"2026-09-15T11:18:31.874743Z","steps":["trace[670432057] 'process raft request' (duration: 114.078894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.208907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.070351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-09-15T11:18:35.208938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.315968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:18:35.209001Z","caller":"traceutil/trace.go:171","msg":"trace[979244987] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:314; }","duration":"257.300165ms","start":"2026-09-15T11:18:34.951687Z","end":"2026-09-15T11:18:35.208987Z","steps":["trace[979244987] 'range keys from in-memory index tree' (duration: 256.884508ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:35.209021Z","caller":"traceutil/trace.go:171","msg":"trace[1984451192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:314; }","duration":"310.45826ms","start":"2026-09-15T11:18:34.898542Z","end":"2026-09-15T11:18:35.209001Z","steps":["trace[1984451192] 'range keys from in-memory index tree' (duration: 310.185436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.209089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:34.898523Z","time spent":"310.554292ms","remote":"127.0.0.1:60958","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-15T11:18:35.209192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.575405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-15T11:18:35.209239Z","caller":"traceutil/trace.go:171","msg":"trace[1769417784] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:314; }","duration":"308.036954ms","start":"2026-09-15T11:18:34.901191Z","end":"2026-09-15T11:18:35.209228Z","steps":["trace[1769417784] 'range keys from in-memory index tree' (duration: 307.511774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.209262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:34.901183Z","time spent":"308.070804ms","remote":"127.0.0.1:32834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-15T11:18:35.209932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.034623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-09-15T11:18:35.210225Z","caller":"traceutil/trace.go:171","msg":"trace[944795592] transaction","detail":"{read_only:false; number_of_response:0; response_revision:314; }","duration":"258.520224ms","start":"2026-09-15T11:18:34.951686Z","end":"2026-09-15T11:18:35.210206Z","steps":["trace[944795592] 'process raft request' (duration: 124.853824ms)","trace[944795592] 'compare' (duration: 131.984503ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:18:35.38735Z","caller":"traceutil/trace.go:171","msg":"trace[666310268] transaction","detail":"{read_only:false; number_of_response:0; response_revision:314; }","duration":"433.258701ms","start":"2026-09-15T11:18:34.95406Z","end":"2026-09-15T11:18:35.387319Z","steps":["trace[666310268] 'process raft request' (duration: 433.176939ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:35.387373Z","caller":"traceutil/trace.go:171","msg":"trace[209600148] transaction","detail":"{read_only:false; number_of_response:0; response_revision:314; }","duration":"432.839314ms","start":"2026-09-15T11:18:34.954516Z","end":"2026-09-15T11:18:35.387355Z","steps":["trace[209600148] 'process raft request' (duration: 432.742863ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:35.387402Z","caller":"traceutil/trace.go:171","msg":"trace[896378342] transaction","detail":"{read_only:false; number_of_response:0; response_revision:314; }","duration":"433.386223ms","start":"2026-09-15T11:18:34.954001Z","end":"2026-09-15T11:18:35.387387Z","steps":["trace[896378342] 'process raft request' (duration: 433.170519ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.387502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:34.954055Z","time spent":"433.380002ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T11:18:35.387516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:34.953989Z","time spent":"433.461054ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T11:18:35.387533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:34.954509Z","time spent":"432.941696ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T11:18:35.387619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.192143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:18:35.387308Z","caller":"traceutil/trace.go:171","msg":"trace[1676707819] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:326; }","duration":"176.864968ms","start":"2026-09-15T11:18:35.210412Z","end":"2026-09-15T11:18:35.387277Z","steps":["trace[1676707819] 'read index received' (duration: 176.823517ms)","trace[1676707819] 'applied index is now lower than readState.Index' (duration: 40.571µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:18:35.387701Z","caller":"traceutil/trace.go:171","msg":"trace[628077521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:314; }","duration":"177.255694ms","start":"2026-09-15T11:18:35.210404Z","end":"2026-09-15T11:18:35.38766Z","steps":["trace[628077521] 'agreement among raft nodes before linearized reading' (duration: 177.157312ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.888383Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056231044027264969,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-15T11:18:35.964543Z","caller":"traceutil/trace.go:171","msg":"trace[1530575385] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:328; }","duration":"576.855333ms","start":"2026-09-15T11:18:35.387662Z","end":"2026-09-15T11:18:35.964517Z","steps":["trace[1530575385] 'read index received' (duration: 576.562999ms)","trace[1530575385] 'applied index is now lower than readState.Index' (duration: 291.234µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:18:35.964715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.057108ms","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-15T11:18:35.964748Z","caller":"traceutil/trace.go:171","msg":"trace[687116191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:316; }","duration":"699.1402ms","start":"2026-09-15T11:18:35.265602Z","end":"2026-09-15T11:18:35.964742Z","steps":["trace[687116191] 'agreement among raft nodes before linearized reading' (duration: 699.040478ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.964769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:35.265583Z","time spent":"699.18235ms","remote":"127.0.0.1:33036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-15T11:18:35.964784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.431537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-09-15T11:18:35.964881Z","caller":"traceutil/trace.go:171","msg":"trace[706904969] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:316; }","duration":"753.559519ms","start":"2026-09-15T11:18:35.211304Z","end":"2026-09-15T11:18:35.964863Z","steps":["trace[706904969] 'agreement among raft nodes before linearized reading' (duration: 753.388357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.96492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:35.211292Z","time spent":"753.619681ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4350,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-09-15T11:18:35.964602Z","caller":"traceutil/trace.go:171","msg":"trace[1544035078] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"751.942223ms","start":"2026-09-15T11:18:35.21263Z","end":"2026-09-15T11:18:35.964572Z","steps":["trace[1544035078] 'process raft request' (duration: 751.694209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.965077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:35.212605Z","time spent":"752.430521ms","remote":"127.0.0.1:32944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T11:18:35.965125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.310792ms","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-15T11:18:35.965147Z","caller":"traceutil/trace.go:171","msg":"trace[435529947] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:316; }","duration":"488.373014ms","start":"2026-09-15T11:18:35.476769Z","end":"2026-09-15T11:18:35.965142Z","steps":["trace[435529947] 'agreement among raft nodes before linearized reading' (duration: 488.330373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.965163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:35.476746Z","time spent":"488.412994ms","remote":"127.0.0.1:33036","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-15T11:18:35.964673Z","caller":"traceutil/trace.go:171","msg":"trace[1957435060] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"711.145563ms","start":"2026-09-15T11:18:35.253508Z","end":"2026-09-15T11:18:35.964654Z","steps":["trace[1957435060] 'process raft request' (duration: 710.95143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.965295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.78057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-09-15T11:18:35.965315Z","caller":"traceutil/trace.go:171","msg":"trace[1038656843] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:316; }","duration":"574.82164ms","start":"2026-09-15T11:18:35.390489Z","end":"2026-09-15T11:18:35.965311Z","steps":["trace[1038656843] 'agreement among raft nodes before linearized reading' (duration: 574.78518ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.965337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:35.390479Z","time spent":"574.854541ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5921,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-09-15T11:18:35.965428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:35.253484Z","time spent":"711.731674ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T11:18:35.965511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.015053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-09-15T11:18:35.965531Z","caller":"traceutil/trace.go:171","msg":"trace[1748703098] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:316; }","duration":"575.107075ms","start":"2026-09-15T11:18:35.39042Z","end":"2026-09-15T11:18:35.965527Z","steps":["trace[1748703098] 'agreement among raft nodes before linearized reading' (duration: 575.073434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.965544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:35.390397Z","time spent":"575.143306ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5537,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-09-15T11:18:35.967663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.172058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-09-15T11:18:35.9678Z","caller":"traceutil/trace.go:171","msg":"trace[781997953] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:316; }","duration":"577.330992ms","start":"2026-09-15T11:18:35.39041Z","end":"2026-09-15T11:18:35.967741Z","steps":["trace[781997953] 'agreement among raft nodes before linearized reading' (duration: 574.684469ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:35.968076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:35.390386Z","time spent":"577.675017ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4004,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-09-15T11:18:36.152863Z","caller":"traceutil/trace.go:171","msg":"trace[1549649092] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"180.846375ms","start":"2026-09-15T11:18:35.971972Z","end":"2026-09-15T11:18:36.152819Z","steps":["trace[1549649092] 'process raft request' (duration: 180.736213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:36.152995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.649135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-15T11:18:36.152897Z","caller":"traceutil/trace.go:171","msg":"trace[1162423008] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:331; }","duration":"179.491883ms","start":"2026-09-15T11:18:35.973329Z","end":"2026-09-15T11:18:36.152821Z","steps":["trace[1162423008] 'read index received' (duration: 179.472873ms)","trace[1162423008] 'applied index is now lower than readState.Index' (duration: 15.89µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:18:36.153182Z","caller":"traceutil/trace.go:171","msg":"trace[693069747] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:317; }","duration":"179.864608ms","start":"2026-09-15T11:18:35.973302Z","end":"2026-09-15T11:18:36.153167Z","steps":["trace[693069747] 'agreement among raft nodes before linearized reading' (duration: 179.630975ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:36.157909Z","caller":"traceutil/trace.go:171","msg":"trace[504214765] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"184.325265ms","start":"2026-09-15T11:18:35.973576Z","end":"2026-09-15T11:18:36.157901Z","steps":["trace[504214765] 'process raft request' (duration: 184.120391ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:36.157895Z","caller":"traceutil/trace.go:171","msg":"trace[496687057] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"165.776975ms","start":"2026-09-15T11:18:35.992102Z","end":"2026-09-15T11:18:36.157879Z","steps":["trace[496687057] 'process raft request' (duration: 165.694624ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:16.046141Z","caller":"traceutil/trace.go:171","msg":"trace[809306778] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"169.460347ms","start":"2026-09-15T11:19:15.876657Z","end":"2026-09-15T11:19:16.046117Z","steps":["trace[809306778] 'process raft request' (duration: 164.717192ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:16.046379Z","caller":"traceutil/trace.go:171","msg":"trace[158303887] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"156.277641ms","start":"2026-09-15T11:19:15.890074Z","end":"2026-09-15T11:19:16.046352Z","steps":["trace[158303887] 'process raft request' (duration: 156.124975ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:16.047078Z","caller":"traceutil/trace.go:171","msg":"trace[815572555] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"109.348415ms","start":"2026-09-15T11:19:15.937715Z","end":"2026-09-15T11:19:16.047063Z","steps":["trace[815572555] 'process raft request' (duration: 108.661786ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:16.321906Z","caller":"traceutil/trace.go:171","msg":"trace[428216777] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"200.448446ms","start":"2026-09-15T11:19:16.12143Z","end":"2026-09-15T11:19:16.321879Z","steps":["trace[428216777] 'process raft request' (duration: 168.608107ms)","trace[428216777] 'compare' (duration: 31.474884ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:19:16.322069Z","caller":"traceutil/trace.go:171","msg":"trace[628050050] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"186.159839ms","start":"2026-09-15T11:19:16.13587Z","end":"2026-09-15T11:19:16.32203Z","steps":["trace[628050050] 'process raft request' (duration: 185.891428ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:16.322582Z","caller":"traceutil/trace.go:171","msg":"trace[1554532470] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"182.323011ms","start":"2026-09-15T11:19:16.140243Z","end":"2026-09-15T11:19:16.322566Z","steps":["trace[1554532470] 'process raft request' (duration: 181.5788ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:16.322735Z","caller":"traceutil/trace.go:171","msg":"trace[212917662] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"114.635399ms","start":"2026-09-15T11:19:16.208062Z","end":"2026-09-15T11:19:16.322698Z","steps":["trace[212917662] 'process raft request' (duration: 114.551215ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:16.322953Z","caller":"traceutil/trace.go:171","msg":"trace[2089154729] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"142.552038ms","start":"2026-09-15T11:19:16.180388Z","end":"2026-09-15T11:19:16.32294Z","steps":["trace[2089154729] 'process raft request' (duration: 142.021865ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:17.196358Z","caller":"traceutil/trace.go:171","msg":"trace[772297546] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"129.732655ms","start":"2026-09-15T11:19:17.066602Z","end":"2026-09-15T11:19:17.196335Z","steps":["trace[772297546] 'process raft request' (duration: 103.713215ms)","trace[772297546] 'compare' (duration: 25.355653ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:19:17.196738Z","caller":"traceutil/trace.go:171","msg":"trace[916510843] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"120.626467ms","start":"2026-09-15T11:19:17.076104Z","end":"2026-09-15T11:19:17.19673Z","steps":["trace[916510843] 'process raft request' (duration: 119.99156ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:22.363419Z","caller":"traceutil/trace.go:171","msg":"trace[256146462] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"108.57011ms","start":"2026-09-15T11:19:22.254817Z","end":"2026-09-15T11:19:22.363387Z","steps":["trace[256146462] 'process raft request' (duration: 108.284378ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:22.782535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.354797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T11:19:22.782742Z","caller":"traceutil/trace.go:171","msg":"trace[1122218501] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"297.072311ms","start":"2026-09-15T11:19:22.485651Z","end":"2026-09-15T11:19:22.782723Z","steps":["trace[1122218501] 'read index received' (duration: 70.203µs)","trace[1122218501] 'applied index is now lower than readState.Index' (duration: 297.000518ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:19:22.782817Z","caller":"traceutil/trace.go:171","msg":"trace[790577651] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"362.368265ms","start":"2026-09-15T11:19:22.420408Z","end":"2026-09-15T11:19:22.782776Z","steps":["trace[790577651] 'process raft request' (duration: 35.669394ms)","trace[790577651] 'compare' (duration: 326.22054ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:19:22.783039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:22.420373Z","time spent":"362.534012ms","remote":"127.0.0.1:33036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T11:19:22.78341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.761741ms","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-15T11:19:22.783507Z","caller":"traceutil/trace.go:171","msg":"trace[850449502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"297.906048ms","start":"2026-09-15T11:19:22.485572Z","end":"2026-09-15T11:19:22.783478Z","steps":["trace[850449502] 'agreement among raft nodes before linearized reading' (duration: 297.357543ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:23.059824Z","caller":"traceutil/trace.go:171","msg":"trace[1163323545] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"276.901495ms","start":"2026-09-15T11:19:22.782833Z","end":"2026-09-15T11:19:23.059735Z","steps":["trace[1163323545] 'read index received' (duration: 275.901091ms)","trace[1163323545] 'applied index is now lower than readState.Index' (duration: 998.614µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:19:23.059888Z","caller":"traceutil/trace.go:171","msg":"trace[1921402201] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"289.511013ms","start":"2026-09-15T11:19:22.77033Z","end":"2026-09-15T11:19:23.059841Z","steps":["trace[1921402201] 'process raft request' (duration: 288.622704ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:23.060243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.086484ms","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-15T11:19:23.060393Z","caller":"traceutil/trace.go:171","msg":"trace[36714408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:663; }","duration":"460.44087ms","start":"2026-09-15T11:19:22.599935Z","end":"2026-09-15T11:19:23.060376Z","steps":["trace[36714408] 'agreement among raft nodes before linearized reading' (duration: 459.944907ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:23.060467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:22.599898Z","time spent":"460.556425ms","remote":"127.0.0.1:33036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-15T11:19:23.11214Z","caller":"traceutil/trace.go:171","msg":"trace[1510767] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"323.20404ms","start":"2026-09-15T11:19:22.78891Z","end":"2026-09-15T11:19:23.112114Z","steps":["trace[1510767] 'process raft request' (duration: 323.009592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:23.112406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.464245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:19:23.112502Z","caller":"traceutil/trace.go:171","msg":"trace[1708951013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"214.638113ms","start":"2026-09-15T11:19:22.897837Z","end":"2026-09-15T11:19:23.112476Z","steps":["trace[1708951013] 'agreement among raft nodes before linearized reading' (duration: 214.463256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:23.112367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:22.788882Z","time spent":"323.337157ms","remote":"127.0.0.1:33036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T11:19:23.642703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.414451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-15T11:19:23.642805Z","caller":"traceutil/trace.go:171","msg":"trace[2001424615] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"412.50329ms","start":"2026-09-15T11:19:23.230285Z","end":"2026-09-15T11:19:23.642789Z","steps":["trace[2001424615] 'read index received' (duration: 119.874838ms)","trace[2001424615] 'applied index is now lower than readState.Index' (duration: 292.627322ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:19:23.642957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.688239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-15T11:19:23.642981Z","caller":"traceutil/trace.go:171","msg":"trace[1536697172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"412.764922ms","start":"2026-09-15T11:19:23.230207Z","end":"2026-09-15T11:19:23.642972Z","steps":["trace[1536697172] 'agreement among raft nodes before linearized reading' (duration: 412.622515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:23.64301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:23.230185Z","time spent":"412.817744ms","remote":"127.0.0.1:33036","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":"info","ts":"2026-09-15T11:19:23.643133Z","caller":"traceutil/trace.go:171","msg":"trace[2845129] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"497.023575ms","start":"2026-09-15T11:19:23.146036Z","end":"2026-09-15T11:19:23.64306Z","steps":["trace[2845129] 'process raft request' (duration: 204.185885ms)","trace[2845129] 'compare' (duration: 292.207432ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:19:23.643411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:23.146012Z","time spent":"497.254945ms","remote":"127.0.0.1:32992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T11:19:23.832496Z","caller":"traceutil/trace.go:171","msg":"trace[1207169335] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"181.11348ms","start":"2026-09-15T11:19:23.651355Z","end":"2026-09-15T11:19:23.832469Z","steps":["trace[1207169335] 'process raft request' (duration: 160.216032ms)","trace[1207169335] 'compare' (duration: 20.681559ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:19:23.832813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.233225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-15T11:19:23.832882Z","caller":"traceutil/trace.go:171","msg":"trace[356256862] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:667; }","duration":"181.401412ms","start":"2026-09-15T11:19:23.651463Z","end":"2026-09-15T11:19:23.832864Z","steps":["trace[356256862] 'agreement among raft nodes before linearized reading' (duration: 181.233205ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:23.832457Z","caller":"traceutil/trace.go:171","msg":"trace[537797334] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"180.912741ms","start":"2026-09-15T11:19:23.651516Z","end":"2026-09-15T11:19:23.832429Z","steps":["trace[537797334] 'read index received' (duration: 160.053845ms)","trace[537797334] 'applied index is now lower than readState.Index' (duration: 20.857506ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:19:25.384053Z","caller":"traceutil/trace.go:171","msg":"trace[1342023531] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"105.11792ms","start":"2026-09-15T11:19:25.278913Z","end":"2026-09-15T11:19:25.384031Z","steps":["trace[1342023531] 'process raft request' (duration: 104.962073ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:40.104089Z","caller":"traceutil/trace.go:171","msg":"trace[92130282] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"199.507502ms","start":"2026-09-15T11:19:39.904558Z","end":"2026-09-15T11:19:40.104066Z","steps":["trace[92130282] 'process raft request' (duration: 199.351364ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:10.559108Z","caller":"traceutil/trace.go:171","msg":"trace[1323032011] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"282.073817ms","start":"2026-09-15T11:20:10.277005Z","end":"2026-09-15T11:20:10.559079Z","steps":["trace[1323032011] 'process raft request' (duration: 281.935479ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:25.642264Z","caller":"traceutil/trace.go:171","msg":"trace[1821375439] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"117.813905ms","start":"2026-09-15T11:20:25.524408Z","end":"2026-09-15T11:20:25.642222Z","steps":["trace[1821375439] 'read index received' (duration: 115.564721ms)","trace[1821375439] 'applied index is now lower than readState.Index' (duration: 2.247854ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:20:25.642593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.171616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-15T11:20:25.642583Z","caller":"traceutil/trace.go:171","msg":"trace[1524393981] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"172.816726ms","start":"2026-09-15T11:20:25.46959Z","end":"2026-09-15T11:20:25.642407Z","steps":["trace[1524393981] 'process raft request' (duration: 170.317197ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:25.642658Z","caller":"traceutil/trace.go:171","msg":"trace[765473133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1369; }","duration":"118.287952ms","start":"2026-09-15T11:20:25.524353Z","end":"2026-09-15T11:20:25.642641Z","steps":["trace[765473133] 'agreement among raft nodes before linearized reading' (duration: 118.036168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:58.090503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.446333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T11:20:58.090753Z","caller":"traceutil/trace.go:171","msg":"trace[840220646] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1992; }","duration":"192.956154ms","start":"2026-09-15T11:20:57.897774Z","end":"2026-09-15T11:20:58.09073Z","steps":["trace[840220646] 'read index received' (duration: 58.493µs)","trace[840220646] 'applied index is now lower than readState.Index' (duration: 192.895771ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:20:58.09081Z","caller":"traceutil/trace.go:171","msg":"trace[899402452] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"295.404345ms","start":"2026-09-15T11:20:57.795371Z","end":"2026-09-15T11:20:58.090776Z","steps":["trace[899402452] 'process raft request' (duration: 17.011572ms)","trace[899402452] 'compare' (duration: 276.787862ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:20:58.090923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.119254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:20:58.091044Z","caller":"traceutil/trace.go:171","msg":"trace[1711365941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1919; }","duration":"193.311688ms","start":"2026-09-15T11:20:57.897702Z","end":"2026-09-15T11:20:58.091013Z","steps":["trace[1711365941] 'agreement among raft nodes before linearized reading' (duration: 193.117785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:58.091134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.241562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-15T11:20:58.091203Z","caller":"traceutil/trace.go:171","msg":"trace[1589688645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1919; }","duration":"176.364171ms","start":"2026-09-15T11:20:57.914826Z","end":"2026-09-15T11:20:58.09119Z","steps":["trace[1589688645] 'agreement among raft nodes before linearized reading' (duration: 176.103664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:58.091523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.454896ms","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-15T11:20:58.091626Z","caller":"traceutil/trace.go:171","msg":"trace[246965649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1919; }","duration":"152.561423ms","start":"2026-09-15T11:20:57.939047Z","end":"2026-09-15T11:20:58.091608Z","steps":["trace[246965649] 'agreement among raft nodes before linearized reading' (duration: 152.096724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:58.33972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.379778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T11:20:58.340038Z","caller":"traceutil/trace.go:171","msg":"trace[1641911259] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"242.904273ms","start":"2026-09-15T11:20:58.097087Z","end":"2026-09-15T11:20:58.339991Z","steps":["trace[1641911259] 'process raft request' (duration: 242.792236ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:58.340253Z","caller":"traceutil/trace.go:171","msg":"trace[396117914] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"243.126298ms","start":"2026-09-15T11:20:58.097071Z","end":"2026-09-15T11:20:58.340197Z","steps":["trace[396117914] 'process raft request' (duration: 129.179064ms)","trace[396117914] 'compare' (duration: 113.280501ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:20:58.609035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.983706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T11:20:58.609242Z","caller":"traceutil/trace.go:171","msg":"trace[530080144] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1996; }","duration":"192.625554ms","start":"2026-09-15T11:20:58.416597Z","end":"2026-09-15T11:20:58.609223Z","steps":["trace[530080144] 'read index received' (duration: 91.313036ms)","trace[530080144] 'applied index is now lower than readState.Index' (duration: 101.310898ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:20:58.6094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.798125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-09-15T11:20:58.609427Z","caller":"traceutil/trace.go:171","msg":"trace[1354590109] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1923; }","duration":"192.856959ms","start":"2026-09-15T11:20:58.416559Z","end":"2026-09-15T11:20:58.609416Z","steps":["trace[1354590109] 'agreement among raft nodes before linearized reading' (duration: 192.70982ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:58.609413Z","caller":"traceutil/trace.go:171","msg":"trace[1963951616] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"253.502642ms","start":"2026-09-15T11:20:58.35586Z","end":"2026-09-15T11:20:58.609363Z","steps":["trace[1963951616] 'process raft request' (duration: 152.086338ms)","trace[1963951616] 'compare' (duration: 100.780223ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:21:11.622811Z","caller":"traceutil/trace.go:171","msg":"trace[1632107031] linearizableReadLoop","detail":"{readStateIndex:2110; appliedIndex:2109; }","duration":"190.526946ms","start":"2026-09-15T11:21:11.432257Z","end":"2026-09-15T11:21:11.622784Z","steps":["trace[1632107031] 'read index received' (duration: 190.482373ms)","trace[1632107031] 'applied index is now lower than readState.Index' (duration: 43.283µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:21:11.622893Z","caller":"traceutil/trace.go:171","msg":"trace[363134702] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"228.82601ms","start":"2026-09-15T11:21:11.394042Z","end":"2026-09-15T11:21:11.622868Z","steps":["trace[363134702] 'process raft request' (duration: 228.559783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:21:11.623076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.807293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-15T11:21:11.623124Z","caller":"traceutil/trace.go:171","msg":"trace[1726441142] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2034; }","duration":"190.91037ms","start":"2026-09-15T11:21:11.432197Z","end":"2026-09-15T11:21:11.623108Z","steps":["trace[1726441142] 'agreement among raft nodes before linearized reading' (duration: 190.739859ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:21:11.625009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.048894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-09-15T11:21:11.625123Z","caller":"traceutil/trace.go:171","msg":"trace[1464843918] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2035; }","duration":"165.197693ms","start":"2026-09-15T11:21:11.45991Z","end":"2026-09-15T11:21:11.625108Z","steps":["trace[1464843918] 'agreement among raft nodes before linearized reading' (duration: 164.955218ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:21:11.625339Z","caller":"traceutil/trace.go:171","msg":"trace[424127547] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"147.166999ms","start":"2026-09-15T11:21:11.478129Z","end":"2026-09-15T11:21:11.625296Z","steps":["trace[424127547] 'process raft request' (duration: 146.591721ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:21:27.80253Z","caller":"traceutil/trace.go:171","msg":"trace[755409733] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2216; }","duration":"102.416764ms","start":"2026-09-15T11:21:27.700059Z","end":"2026-09-15T11:21:27.802476Z","steps":["trace[755409733] 'read index received' (duration: 3.642778ms)","trace[755409733] 'applied index is now lower than readState.Index' (duration: 98.773036ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:21:27.802641Z","caller":"traceutil/trace.go:171","msg":"trace[1592774359] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"114.175138ms","start":"2026-09-15T11:21:27.688432Z","end":"2026-09-15T11:21:27.802608Z","steps":["trace[1592774359] 'process raft request' (duration: 113.722359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:21:27.802769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.687121ms","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-15T11:21:27.802816Z","caller":"traceutil/trace.go:171","msg":"trace[681866703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2138; }","duration":"102.786758ms","start":"2026-09-15T11:21:27.700018Z","end":"2026-09-15T11:21:27.802805Z","steps":["trace[681866703] 'agreement among raft nodes before linearized reading' (duration: 102.623687ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:21:33.967368Z","caller":"traceutil/trace.go:171","msg":"trace[1441986310] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2252; }","duration":"137.672191ms","start":"2026-09-15T11:21:33.829656Z","end":"2026-09-15T11:21:33.967328Z","steps":["trace[1441986310] 'read index received' (duration: 137.393443ms)","trace[1441986310] 'applied index is now lower than readState.Index' (duration: 277.228µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:21:33.967482Z","caller":"traceutil/trace.go:171","msg":"trace[1561296978] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"143.411936ms","start":"2026-09-15T11:21:33.824024Z","end":"2026-09-15T11:21:33.967436Z","steps":["trace[1561296978] 'process raft request' (duration: 142.958016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:21:33.967661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.982361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-15T11:21:33.967724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.456772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-15T11:21:33.967749Z","caller":"traceutil/trace.go:171","msg":"trace[1056607274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2172; }","duration":"138.13376ms","start":"2026-09-15T11:21:33.829591Z","end":"2026-09-15T11:21:33.967725Z","steps":["trace[1056607274] 'agreement among raft nodes before linearized reading' (duration: 137.920637ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:21:33.96784Z","caller":"traceutil/trace.go:171","msg":"trace[364429394] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"125.643683ms","start":"2026-09-15T11:21:33.842174Z","end":"2026-09-15T11:21:33.967817Z","steps":["trace[364429394] 'agreement among raft nodes before linearized reading' (duration: 125.345684ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:21:34.394046Z","caller":"traceutil/trace.go:171","msg":"trace[1296704131] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"162.609229ms","start":"2026-09-15T11:21:34.231403Z","end":"2026-09-15T11:21:34.394013Z","steps":["trace[1296704131] 'process raft request' (duration: 162.278286ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:22:28.431725Z","caller":"traceutil/trace.go:171","msg":"trace[1694918234] linearizableReadLoop","detail":"{readStateIndex:2601; appliedIndex:2599; }","duration":"161.358175ms","start":"2026-09-15T11:22:28.270337Z","end":"2026-09-15T11:22:28.431695Z","steps":["trace[1694918234] 'read index received' (duration: 79.510547ms)","trace[1694918234] 'applied index is now lower than readState.Index' (duration: 81.846348ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:22:28.431907Z","caller":"traceutil/trace.go:171","msg":"trace[1160857112] transaction","detail":"{read_only:false; response_revision:2506; number_of_response:1; }","duration":"205.826554ms","start":"2026-09-15T11:22:28.226062Z","end":"2026-09-15T11:22:28.431888Z","steps":["trace[1160857112] 'process raft request' (duration: 123.797026ms)","trace[1160857112] 'compare' (duration: 81.339112ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:22:28.432344Z","caller":"traceutil/trace.go:171","msg":"trace[463770902] transaction","detail":"{read_only:false; response_revision:2507; number_of_response:1; }","duration":"205.753994ms","start":"2026-09-15T11:22:28.226574Z","end":"2026-09-15T11:22:28.432328Z","steps":["trace[463770902] 'process raft request' (duration: 205.004139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:28.432696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.336128ms","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-15T11:22:28.432817Z","caller":"traceutil/trace.go:171","msg":"trace[915605330] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2508; }","duration":"162.517577ms","start":"2026-09-15T11:22:28.270284Z","end":"2026-09-15T11:22:28.432801Z","steps":["trace[915605330] 'agreement among raft nodes before linearized reading' (duration: 162.261039ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:28.641251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.986266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-09-15T11:22:28.641612Z","caller":"traceutil/trace.go:171","msg":"trace[1552450720] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2509; }","duration":"105.202925ms","start":"2026-09-15T11:22:28.536199Z","end":"2026-09-15T11:22:28.641402Z","steps":["trace[1552450720] 'range keys from in-memory index tree' (duration: 104.728859ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:22:39.086967Z","caller":"traceutil/trace.go:171","msg":"trace[225811133] linearizableReadLoop","detail":"{readStateIndex:2716; appliedIndex:2715; }","duration":"337.859845ms","start":"2026-09-15T11:22:38.749078Z","end":"2026-09-15T11:22:39.086937Z","steps":["trace[225811133] 'read index received' (duration: 337.698106ms)","trace[225811133] 'applied index is now lower than readState.Index' (duration: 160.909µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:22:39.087283Z","caller":"traceutil/trace.go:171","msg":"trace[21010572] transaction","detail":"{read_only:false; response_revision:2619; number_of_response:1; }","duration":"339.399731ms","start":"2026-09-15T11:22:38.747871Z","end":"2026-09-15T11:22:39.087271Z","steps":["trace[21010572] 'process raft request' (duration: 338.936812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:39.087377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:22:38.747846Z","time spent":"339.466271ms","remote":"127.0.0.1:33036","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-15T11:22:39.087633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.557303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-15T11:22:39.087664Z","caller":"traceutil/trace.go:171","msg":"trace[251486486] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2619; }","duration":"338.613474ms","start":"2026-09-15T11:22:38.74904Z","end":"2026-09-15T11:22:39.087653Z","steps":["trace[251486486] 'agreement among raft nodes before linearized reading' (duration: 338.498324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:39.087692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:22:38.748968Z","time spent":"338.717644ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-15T11:22:39.092381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.679553ms","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-15T11:22:39.092452Z","caller":"traceutil/trace.go:171","msg":"trace[737013141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2620; }","duration":"169.776804ms","start":"2026-09-15T11:22:38.922659Z","end":"2026-09-15T11:22:39.092436Z","steps":["trace[737013141] 'agreement among raft nodes before linearized reading' (duration: 169.557695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:39.092672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.163641ms","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-15T11:22:39.092702Z","caller":"traceutil/trace.go:171","msg":"trace[966268579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2620; }","duration":"182.229661ms","start":"2026-09-15T11:22:38.910463Z","end":"2026-09-15T11:22:39.092693Z","steps":["trace[966268579] 'agreement among raft nodes before linearized reading' (duration: 182.142662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:39.092871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.638724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-15T11:22:39.092899Z","caller":"traceutil/trace.go:171","msg":"trace[986469396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2620; }","duration":"315.701574ms","start":"2026-09-15T11:22:38.777188Z","end":"2026-09-15T11:22:39.09289Z","steps":["trace[986469396] 'agreement among raft nodes before linearized reading' (duration: 315.621944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:39.092925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:22:38.777168Z","time spent":"315.748824ms","remote":"127.0.0.1:33036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-15T11:22:39.093135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.102845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-09-15T11:22:39.093162Z","caller":"traceutil/trace.go:171","msg":"trace[2131328513] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2620; }","duration":"339.173175ms","start":"2026-09-15T11:22:38.75398Z","end":"2026-09-15T11:22:39.093153Z","steps":["trace[2131328513] 'agreement among raft nodes before linearized reading' (duration: 339.089935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:39.093185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:22:38.75397Z","time spent":"339.208865ms","remote":"127.0.0.1:32928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1390,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-09-15T11:23:11.562347Z","caller":"traceutil/trace.go:171","msg":"trace[489150401] linearizableReadLoop","detail":"{readStateIndex:2903; appliedIndex:2902; }","duration":"145.51495ms","start":"2026-09-15T11:23:11.416814Z","end":"2026-09-15T11:23:11.562329Z","steps":["trace[489150401] 'read index received' (duration: 82.50099ms)","trace[489150401] 'applied index is now lower than readState.Index' (duration: 63.01321ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:23:11.562643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.798973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-15T11:23:11.56272Z","caller":"traceutil/trace.go:171","msg":"trace[564100084] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2800; }","duration":"145.913244ms","start":"2026-09-15T11:23:11.416788Z","end":"2026-09-15T11:23:11.562702Z","steps":["trace[564100084] 'agreement among raft nodes before linearized reading' (duration: 145.667471ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:23:11.563047Z","caller":"traceutil/trace.go:171","msg":"trace[2036125009] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"170.291729ms","start":"2026-09-15T11:23:11.392737Z","end":"2026-09-15T11:23:11.563029Z","steps":["trace[2036125009] 'process raft request' (duration: 106.619761ms)","trace[2036125009] 'compare' (duration: 62.881039ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:23:16.957579Z","caller":"traceutil/trace.go:171","msg":"trace[1938887303] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"118.921653ms","start":"2026-09-15T11:23:16.838615Z","end":"2026-09-15T11:23:16.957537Z","steps":["trace[1938887303] 'process raft request' (duration: 118.64576ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:27:50.883985Z","caller":"traceutil/trace.go:171","msg":"trace[2124227467] linearizableReadLoop","detail":"{readStateIndex:5010; appliedIndex:5009; }","duration":"114.445124ms","start":"2026-09-15T11:27:50.7695Z","end":"2026-09-15T11:27:50.883945Z","steps":["trace[2124227467] 'read index received' (duration: 105.182556ms)","trace[2124227467] 'applied index is now lower than readState.Index' (duration: 9.261608ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:27:50.884134Z","caller":"traceutil/trace.go:171","msg":"trace[530078600] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"115.352071ms","start":"2026-09-15T11:27:50.768766Z","end":"2026-09-15T11:27:50.884118Z","steps":["trace[530078600] 'process raft request' (duration: 106.00552ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:27:50.884372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.846275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-15T11:27:50.884508Z","caller":"traceutil/trace.go:171","msg":"trace[1836454547] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4824; }","duration":"115.003844ms","start":"2026-09-15T11:27:50.769482Z","end":"2026-09-15T11:27:50.884486Z","steps":["trace[1836454547] 'agreement among raft nodes before linearized reading' (duration: 114.720799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:27:50.884437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.519889ms","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-15T11:27:50.884986Z","caller":"traceutil/trace.go:171","msg":"trace[731130580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4824; }","duration":"112.054299ms","start":"2026-09-15T11:27:50.77289Z","end":"2026-09-15T11:27:50.884944Z","steps":["trace[731130580] 'agreement among raft nodes before linearized reading' (duration: 111.345701ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:27:57.88272Z","caller":"traceutil/trace.go:171","msg":"trace[1727794044] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"126.126256ms","start":"2026-09-15T11:27:57.75657Z","end":"2026-09-15T11:27:57.882696Z","steps":["trace[1727794044] 'process raft request' (duration: 126.058993ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:27:57.883141Z","caller":"traceutil/trace.go:171","msg":"trace[188554622] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"128.591426ms","start":"2026-09-15T11:27:57.754538Z","end":"2026-09-15T11:27:57.883129Z","steps":["trace[188554622] 'process raft request' (duration: 127.772602ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:28:04.606925Z","caller":"traceutil/trace.go:171","msg":"trace[782968059] linearizableReadLoop","detail":"{readStateIndex:5238; appliedIndex:5237; }","duration":"365.461848ms","start":"2026-09-15T11:28:04.241432Z","end":"2026-09-15T11:28:04.606894Z","steps":["trace[782968059] 'read index received' (duration: 365.262248ms)","trace[782968059] 'applied index is now lower than readState.Index' (duration: 197.63µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:28:04.606963Z","caller":"traceutil/trace.go:171","msg":"trace[1332656804] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"409.892885ms","start":"2026-09-15T11:28:04.197031Z","end":"2026-09-15T11:28:04.606924Z","steps":["trace[1332656804] 'process raft request' (duration: 409.628091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:28:04.607151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:28:04.197017Z","time spent":"410.031542ms","remote":"127.0.0.1:32928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1250,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T11:28:04.607172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.732512ms","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-15T11:28:04.607228Z","caller":"traceutil/trace.go:171","msg":"trace[251954593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5044; }","duration":"365.823187ms","start":"2026-09-15T11:28:04.24139Z","end":"2026-09-15T11:28:04.607213Z","steps":["trace[251954593] 'agreement among raft nodes before linearized reading' (duration: 365.636997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:28:04.607273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:28:04.241373Z","time spent":"365.87364ms","remote":"127.0.0.1:33036","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-15T11:28:05.088436Z","caller":"traceutil/trace.go:171","msg":"trace[48412117] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"121.167548ms","start":"2026-09-15T11:28:04.967253Z","end":"2026-09-15T11:28:05.088421Z","steps":["trace[48412117] 'process raft request' (duration: 121.095264ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:28:05.088482Z","caller":"traceutil/trace.go:171","msg":"trace[376605920] linearizableReadLoop","detail":"{readStateIndex:5258; appliedIndex:5257; }","duration":"126.950967ms","start":"2026-09-15T11:28:04.961503Z","end":"2026-09-15T11:28:05.088454Z","steps":["trace[376605920] 'read index received' (duration: 34.510199ms)","trace[376605920] 'applied index is now lower than readState.Index' (duration: 92.438518ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:28:05.088419Z","caller":"traceutil/trace.go:171","msg":"trace[311372193] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"198.785043ms","start":"2026-09-15T11:28:04.889598Z","end":"2026-09-15T11:28:05.088383Z","steps":["trace[311372193] 'process raft request' (duration: 106.398928ms)","trace[311372193] 'compare' (duration: 91.947132ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:28:05.088736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.121934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:28:05.088826Z","caller":"traceutil/trace.go:171","msg":"trace[739344734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5065; }","duration":"191.284204ms","start":"2026-09-15T11:28:04.897493Z","end":"2026-09-15T11:28:05.088777Z","steps":["trace[739344734] 'agreement among raft nodes before linearized reading' (duration: 191.091664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:28:05.089117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.50629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-15T11:28:05.089162Z","caller":"traceutil/trace.go:171","msg":"trace[1614001062] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5065; }","duration":"199.599635ms","start":"2026-09-15T11:28:04.88955Z","end":"2026-09-15T11:28:05.08915Z","steps":["trace[1614001062] 'agreement among raft nodes before linearized reading' (duration: 199.401625ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:28:05.352255Z","caller":"traceutil/trace.go:171","msg":"trace[1302771868] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"212.084765ms","start":"2026-09-15T11:28:05.140127Z","end":"2026-09-15T11:28:05.352212Z","steps":["trace[1302771868] 'process raft request' (duration: 209.613963ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:28:05.365182Z","caller":"traceutil/trace.go:171","msg":"trace[979889068] linearizableReadLoop","detail":"{readStateIndex:5267; appliedIndex:5264; }","duration":"221.467756ms","start":"2026-09-15T11:28:05.143675Z","end":"2026-09-15T11:28:05.365143Z","steps":["trace[979889068] 'read index received' (duration: 206.070595ms)","trace[979889068] 'applied index is now lower than readState.Index' (duration: 15.39617ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:28:05.365226Z","caller":"traceutil/trace.go:171","msg":"trace[1183661265] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"224.820845ms","start":"2026-09-15T11:28:05.140385Z","end":"2026-09-15T11:28:05.365206Z","steps":["trace[1183661265] 'process raft request' (duration: 224.524748ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:28:05.365321Z","caller":"traceutil/trace.go:171","msg":"trace[1201104347] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"223.722976ms","start":"2026-09-15T11:28:05.141588Z","end":"2026-09-15T11:28:05.365311Z","steps":["trace[1201104347] 'process raft request' (duration: 223.451221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:28:05.365503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.811164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-15T11:28:05.365544Z","caller":"traceutil/trace.go:171","msg":"trace[1639991690] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5073; }","duration":"221.881848ms","start":"2026-09-15T11:28:05.143649Z","end":"2026-09-15T11:28:05.365531Z","steps":["trace[1639991690] 'agreement among raft nodes before linearized reading' (duration: 221.756921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:28:05.365628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.126815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-09-15T11:28:05.365673Z","caller":"traceutil/trace.go:171","msg":"trace[1107263085] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5073; }","duration":"166.20886ms","start":"2026-09-15T11:28:05.199452Z","end":"2026-09-15T11:28:05.36566Z","steps":["trace[1107263085] 'agreement among raft nodes before linearized reading' (duration: 166.075593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:28:05.366702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.825998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362739"} {"level":"info","ts":"2026-09-15T11:28:05.366747Z","caller":"traceutil/trace.go:171","msg":"trace[1000398381] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5073; }","duration":"107.910592ms","start":"2026-09-15T11:28:05.258826Z","end":"2026-09-15T11:28:05.366736Z","steps":["trace[1000398381] 'agreement among raft nodes before linearized reading' (duration: 106.978912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:28:05.37035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.876759ms","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-15T11:28:05.37045Z","caller":"traceutil/trace.go:171","msg":"trace[415752330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5073; }","duration":"145.292785ms","start":"2026-09-15T11:28:05.225132Z","end":"2026-09-15T11:28:05.370424Z","steps":["trace[415752330] 'agreement among raft nodes before linearized reading' (duration: 140.723402ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:28:07.861024Z","caller":"traceutil/trace.go:171","msg":"trace[1234780879] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"174.41087ms","start":"2026-09-15T11:28:07.686582Z","end":"2026-09-15T11:28:07.860993Z","steps":["trace[1234780879] 'process raft request' (duration: 174.227641ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:28:27.752555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2888} {"level":"info","ts":"2026-09-15T11:28:27.836036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2888,"took":"82.009372ms","hash":2845672217,"current-db-size-bytes":52584448,"current-db-size":"53 MB","current-db-size-in-use-bytes":28049408,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-15T11:28:27.836106Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2845672217,"revision":2888,"compact-revision":-1} {"level":"info","ts":"2026-09-15T11:33:27.760345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5355} {"level":"info","ts":"2026-09-15T11:33:27.846269Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5355,"took":"84.426888ms","hash":3360958044,"current-db-size-bytes":52584448,"current-db-size":"53 MB","current-db-size-in-use-bytes":19017728,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-15T11:33:27.846351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3360958044,"revision":5355,"compact-revision":2888}