{"level":"warn","ts":"2026-10-17T11:31:51.250017Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T11:31:51.250114Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.45: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.45:2380","--initial-cluster=instance=https://162.253.55.45:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.45:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.45:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-17T11:31:51.250189Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T11:31:51.250211Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.45:2380"]} {"level":"info","ts":"2026-10-17T11:31:51.250231Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-17T11:31:51.250943Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.45:2379"]} {"level":"info","ts":"2026-10-17T11:31:51.251037Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.45:2380"],"listen-peer-urls":["https://162.253.55.45:2380"],"advertise-client-urls":["https://162.253.55.45:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.45:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.45:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-17T11:31:51.254557Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.263568ms"} {"level":"info","ts":"2026-10-17T11:31:51.263702Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fbe9b088d8de907a","cluster-id":"332637b4e3714c3a"} {"level":"info","ts":"2026-10-17T11:31:51.263834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a switched to configuration voters=()"} {"level":"info","ts":"2026-10-17T11:31:51.263862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became follower at term 0"} {"level":"info","ts":"2026-10-17T11:31:51.263878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fbe9b088d8de907a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-17T11:31:51.263889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became follower at term 1"} {"level":"info","ts":"2026-10-17T11:31:51.263954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a switched to configuration voters=(18152233874894000250)"} {"level":"warn","ts":"2026-10-17T11:31:51.273883Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-17T11:31:51.276419Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-17T11:31:51.278642Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-17T11:31:51.282092Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fbe9b088d8de907a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-17T11:31:51.282585Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fbe9b088d8de907a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-17T11:31:51.282658Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-17T11:31:51.282785Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-17T11:31:51.282841Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-17T11:31:51.284545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a switched to configuration voters=(18152233874894000250)"} {"level":"info","ts":"2026-10-17T11:31:51.284697Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"332637b4e3714c3a","local-member-id":"fbe9b088d8de907a","added-peer-id":"fbe9b088d8de907a","added-peer-peer-urls":["https://162.253.55.45:2380"]} {"level":"info","ts":"2026-10-17T11:31:51.292331Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-17T11:31:51.292425Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.45:2380"} {"level":"info","ts":"2026-10-17T11:31:51.292486Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.45:2380"} {"level":"info","ts":"2026-10-17T11:31:51.292756Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fbe9b088d8de907a","initial-advertise-peer-urls":["https://162.253.55.45:2380"],"listen-peer-urls":["https://162.253.55.45:2380"],"advertise-client-urls":["https://162.253.55.45:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.45:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-17T11:31:51.29285Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-17T11:31:51.564709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a is starting a new election at term 1"} {"level":"info","ts":"2026-10-17T11:31:51.564771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became pre-candidate at term 1"} {"level":"info","ts":"2026-10-17T11:31:51.564805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a received MsgPreVoteResp from fbe9b088d8de907a at term 1"} {"level":"info","ts":"2026-10-17T11:31:51.564824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became candidate at term 2"} {"level":"info","ts":"2026-10-17T11:31:51.564834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a received MsgVoteResp from fbe9b088d8de907a at term 2"} {"level":"info","ts":"2026-10-17T11:31:51.564855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became leader at term 2"} {"level":"info","ts":"2026-10-17T11:31:51.564868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fbe9b088d8de907a elected leader fbe9b088d8de907a at term 2"} {"level":"info","ts":"2026-10-17T11:31:51.566308Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fbe9b088d8de907a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.45:2379]}","request-path":"/0/members/fbe9b088d8de907a/attributes","cluster-id":"332637b4e3714c3a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-17T11:31:51.566363Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T11:31:51.566606Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T11:31:51.566731Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T11:31:51.566749Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-17T11:31:51.566782Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-17T11:31:51.569875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-17T11:31:51.570062Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.45:2379"} {"level":"info","ts":"2026-10-17T11:31:51.578841Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"332637b4e3714c3a","local-member-id":"fbe9b088d8de907a","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T11:31:51.579094Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T11:31:51.579184Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-10-17T11:32:07.701011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.547452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-10-17T11:32:07.70114Z","caller":"traceutil/trace.go:171","msg":"trace[1091337630] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:368; }","duration":"165.761197ms","start":"2026-10-17T11:32:07.535358Z","end":"2026-10-17T11:32:07.701119Z","steps":["trace[1091337630] 'range keys from in-memory index tree' (duration: 165.417648ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:32:13.417768Z","caller":"traceutil/trace.go:171","msg":"trace[1204323813] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"117.948985ms","start":"2026-10-17T11:32:13.299791Z","end":"2026-10-17T11:32:13.41774Z","steps":["trace[1204323813] 'read index received' (duration: 117.709849ms)","trace[1204323813] 'applied index is now lower than readState.Index' (duration: 236.116µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:32:13.417832Z","caller":"traceutil/trace.go:171","msg":"trace[601550902] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"159.212203ms","start":"2026-10-17T11:32:13.258562Z","end":"2026-10-17T11:32:13.417775Z","steps":["trace[601550902] 'process raft request' (duration: 159.046309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:32:13.41796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.150241ms","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-10-17T11:32:13.418018Z","caller":"traceutil/trace.go:171","msg":"trace[1440268742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:383; }","duration":"118.256404ms","start":"2026-10-17T11:32:13.299746Z","end":"2026-10-17T11:32:13.418003Z","steps":["trace[1440268742] 'agreement among raft nodes before linearized reading' (duration: 118.11209ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:32:18.535412Z","caller":"traceutil/trace.go:171","msg":"trace[1308634068] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"186.457268ms","start":"2026-10-17T11:32:18.348894Z","end":"2026-10-17T11:32:18.535351Z","steps":["trace[1308634068] 'process raft request' (duration: 186.300214ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:32:43.516907Z","caller":"traceutil/trace.go:171","msg":"trace[1510861889] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"261.311945ms","start":"2026-10-17T11:32:43.255553Z","end":"2026-10-17T11:32:43.516865Z","steps":["trace[1510861889] 'process raft request' (duration: 261.063499ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:32:43.665765Z","caller":"traceutil/trace.go:171","msg":"trace[712711203] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"381.559483ms","start":"2026-10-17T11:32:43.284145Z","end":"2026-10-17T11:32:43.665705Z","steps":["trace[712711203] 'process raft request' (duration: 380.70568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:32:43.666723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:32:43.284113Z","time spent":"381.7958ms","remote":"127.0.0.1:33118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:32:44.203782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.926335ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:32:44.203948Z","caller":"traceutil/trace.go:171","msg":"trace[1749722413] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:608; }","duration":"428.101769ms","start":"2026-10-17T11:32:43.775805Z","end":"2026-10-17T11:32:44.203907Z","steps":["trace[1749722413] 'range keys from in-memory index tree' (duration: 427.903634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:32:44.204357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.121435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:32:44.204622Z","caller":"traceutil/trace.go:171","msg":"trace[416221484] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"420.907719ms","start":"2026-10-17T11:32:43.783691Z","end":"2026-10-17T11:32:44.204599Z","steps":["trace[416221484] 'read index received' (duration: 130.386834ms)","trace[416221484] 'applied index is now lower than readState.Index' (duration: 290.519595ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:32:44.204659Z","caller":"traceutil/trace.go:171","msg":"trace[191153575] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"467.878728ms","start":"2026-10-17T11:32:43.73674Z","end":"2026-10-17T11:32:44.204619Z","steps":["trace[191153575] 'process raft request' (duration: 467.781165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:32:44.204842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:32:43.73671Z","time spent":"468.037451ms","remote":"127.0.0.1:33368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-17T11:32:44.204834Z","caller":"traceutil/trace.go:171","msg":"trace[1539568598] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"533.707102ms","start":"2026-10-17T11:32:43.670989Z","end":"2026-10-17T11:32:44.204697Z","steps":["trace[1539568598] 'process raft request' (duration: 92.870718ms)","trace[1539568598] 'compare' (duration: 439.866008ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:32:44.204898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.439669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-17T11:32:44.205274Z","caller":"traceutil/trace.go:171","msg":"trace[2054545339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"258.859809ms","start":"2026-10-17T11:32:43.946391Z","end":"2026-10-17T11:32:44.20525Z","steps":["trace[2054545339] 'agreement among raft nodes before linearized reading' (duration: 258.350256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:32:44.204904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.191086ms","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-10-17T11:32:44.205358Z","caller":"traceutil/trace.go:171","msg":"trace[1806979644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"421.71185ms","start":"2026-10-17T11:32:43.78363Z","end":"2026-10-17T11:32:44.205342Z","steps":["trace[1806979644] 'agreement among raft nodes before linearized reading' (duration: 421.094224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:32:44.205404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:32:43.783597Z","time spent":"421.794022ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-17T11:32:44.205548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:32:43.670965Z","time spent":"534.103272ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:32:45.525334Z","caller":"traceutil/trace.go:171","msg":"trace[178650550] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"128.951997ms","start":"2026-10-17T11:32:45.396306Z","end":"2026-10-17T11:32:45.525258Z","steps":["trace[178650550] 'process raft request' (duration: 126.330438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:32:59.338465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.050894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:32:59.338721Z","caller":"traceutil/trace.go:171","msg":"trace[993453108] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"382.212179ms","start":"2026-10-17T11:32:58.956428Z","end":"2026-10-17T11:32:59.33864Z","steps":["trace[993453108] 'process raft request' (duration: 160.881438ms)","trace[993453108] 'compare' (duration: 220.724685ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:32:59.339012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:32:58.956405Z","time spent":"382.437585ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:33:28.56284Z","caller":"traceutil/trace.go:171","msg":"trace[1416707791] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"111.098937ms","start":"2026-10-17T11:33:28.451707Z","end":"2026-10-17T11:33:28.562806Z","steps":["trace[1416707791] 'process raft request' (duration: 110.898802ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:33:29.031274Z","caller":"traceutil/trace.go:171","msg":"trace[1810092391] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"213.26217ms","start":"2026-10-17T11:33:28.817978Z","end":"2026-10-17T11:33:29.03124Z","steps":["trace[1810092391] 'process raft request' (duration: 212.88909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:34:18.830614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.900686ms","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-10-17T11:34:18.830752Z","caller":"traceutil/trace.go:171","msg":"trace[1335511770] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1864; }","duration":"159.097211ms","start":"2026-10-17T11:34:18.67162Z","end":"2026-10-17T11:34:18.830717Z","steps":["trace[1335511770] 'range keys from in-memory index tree' (duration: 158.774813ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:19.035516Z","caller":"traceutil/trace.go:171","msg":"trace[492237540] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1942; }","duration":"140.279096ms","start":"2026-10-17T11:34:18.895197Z","end":"2026-10-17T11:34:19.035476Z","steps":["trace[492237540] 'read index received' (duration: 30.941216ms)","trace[492237540] 'applied index is now lower than readState.Index' (duration: 109.33611ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:34:19.035584Z","caller":"traceutil/trace.go:171","msg":"trace[819795059] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"145.657067ms","start":"2026-10-17T11:34:18.889891Z","end":"2026-10-17T11:34:19.035548Z","steps":["trace[819795059] 'process raft request' (duration: 70.469567ms)","trace[819795059] 'compare' (duration: 74.907043ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:34:19.035854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.615286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-17T11:34:19.035969Z","caller":"traceutil/trace.go:171","msg":"trace[1874407603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1867; }","duration":"140.801981ms","start":"2026-10-17T11:34:18.895147Z","end":"2026-10-17T11:34:19.035949Z","steps":["trace[1874407603] 'agreement among raft nodes before linearized reading' (duration: 140.489612ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:19.294455Z","caller":"traceutil/trace.go:171","msg":"trace[245898253] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"244.601374ms","start":"2026-10-17T11:34:19.049825Z","end":"2026-10-17T11:34:19.294426Z","steps":["trace[245898253] 'read index received' (duration: 210.359412ms)","trace[245898253] 'applied index is now lower than readState.Index' (duration: 34.241102ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:34:19.294552Z","caller":"traceutil/trace.go:171","msg":"trace[1681174325] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"251.201918ms","start":"2026-10-17T11:34:19.043302Z","end":"2026-10-17T11:34:19.294504Z","steps":["trace[1681174325] 'process raft request' (duration: 216.950596ms)","trace[1681174325] 'compare' (duration: 34.051017ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:34:19.294614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.763758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:34:19.294663Z","caller":"traceutil/trace.go:171","msg":"trace[1157946662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1868; }","duration":"244.875971ms","start":"2026-10-17T11:34:19.049769Z","end":"2026-10-17T11:34:19.294645Z","steps":["trace[1157946662] 'agreement among raft nodes before linearized reading' (duration: 244.767708ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:33.022803Z","caller":"traceutil/trace.go:171","msg":"trace[2017270543] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"161.745511ms","start":"2026-10-17T11:34:32.861005Z","end":"2026-10-17T11:34:33.02275Z","steps":["trace[2017270543] 'process raft request' (duration: 160.649502ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:33.510038Z","caller":"traceutil/trace.go:171","msg":"trace[1750589376] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"130.16808ms","start":"2026-10-17T11:34:33.379833Z","end":"2026-10-17T11:34:33.510001Z","steps":["trace[1750589376] 'process raft request' (duration: 129.863012ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:35:10.735889Z","caller":"traceutil/trace.go:171","msg":"trace[714543151] linearizableReadLoop","detail":"{readStateIndex:2284; appliedIndex:2283; }","duration":"207.542213ms","start":"2026-10-17T11:35:10.528306Z","end":"2026-10-17T11:35:10.735848Z","steps":["trace[714543151] 'read index received' (duration: 142.931835ms)","trace[714543151] 'applied index is now lower than readState.Index' (duration: 64.607998ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:35:10.7362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.867662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-17T11:35:10.736299Z","caller":"traceutil/trace.go:171","msg":"trace[886364332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2195; }","duration":"208.021916ms","start":"2026-10-17T11:35:10.528253Z","end":"2026-10-17T11:35:10.736275Z","steps":["trace[886364332] 'agreement among raft nodes before linearized reading' (duration: 207.734679ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:35:10.743774Z","caller":"traceutil/trace.go:171","msg":"trace[1641687087] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"180.451493ms","start":"2026-10-17T11:35:10.563301Z","end":"2026-10-17T11:35:10.743752Z","steps":["trace[1641687087] 'process raft request' (duration: 180.302259ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:35:11.334866Z","caller":"traceutil/trace.go:171","msg":"trace[773622984] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"105.803289ms","start":"2026-10-17T11:35:11.229038Z","end":"2026-10-17T11:35:11.334841Z","steps":["trace[773622984] 'process raft request' (duration: 105.618063ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:36:04.599028Z","caller":"traceutil/trace.go:171","msg":"trace[2019150054] transaction","detail":"{read_only:false; response_revision:2657; number_of_response:1; }","duration":"263.075178ms","start":"2026-10-17T11:36:04.335927Z","end":"2026-10-17T11:36:04.599002Z","steps":["trace[2019150054] 'process raft request' (duration: 262.895694ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:36:04.600006Z","caller":"traceutil/trace.go:171","msg":"trace[1473442078] transaction","detail":"{read_only:false; response_revision:2658; number_of_response:1; }","duration":"227.396507ms","start":"2026-10-17T11:36:04.372582Z","end":"2026-10-17T11:36:04.599978Z","steps":["trace[1473442078] 'process raft request' (duration: 227.190052ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:36:04.600123Z","caller":"traceutil/trace.go:171","msg":"trace[1155690396] transaction","detail":"{read_only:false; response_revision:2659; number_of_response:1; }","duration":"169.28958ms","start":"2026-10-17T11:36:04.430819Z","end":"2026-10-17T11:36:04.600108Z","steps":["trace[1155690396] 'process raft request' (duration: 169.120596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:36:38.022667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.06561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:36:38.022846Z","caller":"traceutil/trace.go:171","msg":"trace[1169443676] transaction","detail":"{read_only:false; response_revision:2860; number_of_response:1; }","duration":"153.609096ms","start":"2026-10-17T11:36:37.869203Z","end":"2026-10-17T11:36:38.022812Z","steps":["trace[1169443676] 'process raft request' (duration: 19.311971ms)","trace[1169443676] 'compare' (duration: 133.920746ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:36:38.314339Z","caller":"traceutil/trace.go:171","msg":"trace[1540291460] linearizableReadLoop","detail":"{readStateIndex:2975; appliedIndex:2974; }","duration":"141.055395ms","start":"2026-10-17T11:36:38.17326Z","end":"2026-10-17T11:36:38.314315Z","steps":["trace[1540291460] 'read index received' (duration: 140.84876ms)","trace[1540291460] 'applied index is now lower than readState.Index' (duration: 205.735µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:36:38.314638Z","caller":"traceutil/trace.go:171","msg":"trace[1137247830] transaction","detail":"{read_only:false; response_revision:2861; number_of_response:1; }","duration":"293.315698ms","start":"2026-10-17T11:36:38.021309Z","end":"2026-10-17T11:36:38.314625Z","steps":["trace[1137247830] 'process raft request' (duration: 292.872298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:36:38.314968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.696172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-17T11:36:38.315023Z","caller":"traceutil/trace.go:171","msg":"trace[1958177968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2861; }","duration":"141.764193ms","start":"2026-10-17T11:36:38.173245Z","end":"2026-10-17T11:36:38.315009Z","steps":["trace[1958177968] 'agreement among raft nodes before linearized reading' (duration: 141.580319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:36:38.315221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.53279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-17T11:36:38.315285Z","caller":"traceutil/trace.go:171","msg":"trace[1906574752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2861; }","duration":"139.640132ms","start":"2026-10-17T11:36:38.175629Z","end":"2026-10-17T11:36:38.315269Z","steps":["trace[1906574752] 'agreement among raft nodes before linearized reading' (duration: 139.488119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:28.237739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.959846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:41:28.237968Z","caller":"traceutil/trace.go:171","msg":"trace[1800853833] linearizableReadLoop","detail":"{readStateIndex:5502; appliedIndex:5500; }","duration":"211.158307ms","start":"2026-10-17T11:41:28.026789Z","end":"2026-10-17T11:41:28.237947Z","steps":["trace[1800853833] 'read index received' (duration: 89.919734ms)","trace[1800853833] 'applied index is now lower than readState.Index' (duration: 121.237213ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:28.237996Z","caller":"traceutil/trace.go:171","msg":"trace[11848773] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"256.804124ms","start":"2026-10-17T11:41:27.981116Z","end":"2026-10-17T11:41:28.23792Z","steps":["trace[11848773] 'process raft request' (duration: 135.56634ms)","trace[11848773] 'compare' (duration: 120.834662ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:28.238078Z","caller":"traceutil/trace.go:171","msg":"trace[1685412957] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"254.87143ms","start":"2026-10-17T11:41:27.983196Z","end":"2026-10-17T11:41:28.238067Z","steps":["trace[1685412957] 'process raft request' (duration: 254.653234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:28.238335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.526638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-17T11:41:28.238371Z","caller":"traceutil/trace.go:171","msg":"trace[1002125404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5298; }","duration":"211.647991ms","start":"2026-10-17T11:41:28.026716Z","end":"2026-10-17T11:41:28.238364Z","steps":["trace[1002125404] 'agreement among raft nodes before linearized reading' (duration: 211.498017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:28.238311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.336155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-17T11:41:28.238426Z","caller":"traceutil/trace.go:171","msg":"trace[851071149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5298; }","duration":"209.537701ms","start":"2026-10-17T11:41:28.02886Z","end":"2026-10-17T11:41:28.238398Z","steps":["trace[851071149] 'agreement among raft nodes before linearized reading' (duration: 209.254743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:28.238616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.887718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:41:28.238665Z","caller":"traceutil/trace.go:171","msg":"trace[1957009740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5298; }","duration":"187.999591ms","start":"2026-10-17T11:41:28.050654Z","end":"2026-10-17T11:41:28.238653Z","steps":["trace[1957009740] 'agreement among raft nodes before linearized reading' (duration: 187.924659ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:30.391661Z","caller":"traceutil/trace.go:171","msg":"trace[2028961414] linearizableReadLoop","detail":"{readStateIndex:5537; appliedIndex:5536; }","duration":"114.686637ms","start":"2026-10-17T11:41:30.276947Z","end":"2026-10-17T11:41:30.391634Z","steps":["trace[2028961414] 'read index received' (duration: 64.764419ms)","trace[2028961414] 'applied index is now lower than readState.Index' (duration: 49.921498ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:30.391834Z","caller":"traceutil/trace.go:171","msg":"trace[1165501610] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"121.610914ms","start":"2026-10-17T11:41:30.270177Z","end":"2026-10-17T11:41:30.391788Z","steps":["trace[1165501610] 'process raft request' (duration: 71.736367ms)","trace[1165501610] 'compare' (duration: 49.586199ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:41:30.391905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.919035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-17T11:41:30.392106Z","caller":"traceutil/trace.go:171","msg":"trace[859042529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5331; }","duration":"115.213673ms","start":"2026-10-17T11:41:30.276852Z","end":"2026-10-17T11:41:30.392065Z","steps":["trace[859042529] 'agreement among raft nodes before linearized reading' (duration: 114.878383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:33.148261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.828792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:41:33.14863Z","caller":"traceutil/trace.go:171","msg":"trace[1121366729] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"217.146477ms","start":"2026-10-17T11:41:32.931445Z","end":"2026-10-17T11:41:33.148592Z","steps":["trace[1121366729] 'process raft request' (duration: 217.056875ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:33.14861Z","caller":"traceutil/trace.go:171","msg":"trace[593269603] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"217.840728ms","start":"2026-10-17T11:41:32.93072Z","end":"2026-10-17T11:41:33.148561Z","steps":["trace[593269603] 'process raft request' (duration: 84.565473ms)","trace[593269603] 'compare' (duration: 132.623827ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:33.148687Z","caller":"traceutil/trace.go:171","msg":"trace[1551786517] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"217.475207ms","start":"2026-10-17T11:41:32.931147Z","end":"2026-10-17T11:41:33.148623Z","steps":["trace[1551786517] 'process raft request' (duration: 217.278522ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:44.610808Z","caller":"traceutil/trace.go:171","msg":"trace[205247879] transaction","detail":"{read_only:false; response_revision:5578; number_of_response:1; }","duration":"143.334261ms","start":"2026-10-17T11:41:44.467441Z","end":"2026-10-17T11:41:44.610775Z","steps":["trace[205247879] 'process raft request' (duration: 66.54697ms)","trace[205247879] 'compare' (duration: 76.601706ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:44.621088Z","caller":"traceutil/trace.go:171","msg":"trace[581968559] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"152.78037ms","start":"2026-10-17T11:41:44.46828Z","end":"2026-10-17T11:41:44.62106Z","steps":["trace[581968559] 'process raft request' (duration: 152.47359ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:44.621117Z","caller":"traceutil/trace.go:171","msg":"trace[732472479] linearizableReadLoop","detail":"{readStateIndex:5795; appliedIndex:5791; }","duration":"148.680854ms","start":"2026-10-17T11:41:44.472402Z","end":"2026-10-17T11:41:44.621083Z","steps":["trace[732472479] 'read index received' (duration: 61.619531ms)","trace[732472479] 'applied index is now lower than readState.Index' (duration: 87.057983ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:44.621234Z","caller":"traceutil/trace.go:171","msg":"trace[1144711479] transaction","detail":"{read_only:false; response_revision:5581; number_of_response:1; }","duration":"151.811003ms","start":"2026-10-17T11:41:44.469396Z","end":"2026-10-17T11:41:44.621207Z","steps":["trace[1144711479] 'process raft request' (duration: 151.636888ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:44.621167Z","caller":"traceutil/trace.go:171","msg":"trace[1956752941] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"151.912395ms","start":"2026-10-17T11:41:44.469204Z","end":"2026-10-17T11:41:44.621117Z","steps":["trace[1956752941] 'process raft request' (duration: 151.765501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:44.621425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.984353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-17T11:41:44.621465Z","caller":"traceutil/trace.go:171","msg":"trace[399557855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5581; }","duration":"149.098826ms","start":"2026-10-17T11:41:44.472354Z","end":"2026-10-17T11:41:44.621453Z","steps":["trace[399557855] 'agreement among raft nodes before linearized reading' (duration: 148.88605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:44.621669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.287191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-17T11:41:44.621833Z","caller":"traceutil/trace.go:171","msg":"trace[1776520295] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5581; }","duration":"136.473497ms","start":"2026-10-17T11:41:44.485301Z","end":"2026-10-17T11:41:44.621775Z","steps":["trace[1776520295] 'agreement among raft nodes before linearized reading' (duration: 136.069236ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:47.344414Z","caller":"traceutil/trace.go:171","msg":"trace[781688746] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"105.911269ms","start":"2026-10-17T11:41:47.238469Z","end":"2026-10-17T11:41:47.344381Z","steps":["trace[781688746] 'process raft request' (duration: 105.706993ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:51.701009Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2936} {"level":"info","ts":"2026-10-17T11:41:51.785595Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2936,"took":"82.501463ms","hash":1705071320,"current-db-size-bytes":56291328,"current-db-size":"56 MB","current-db-size-in-use-bytes":31563776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T11:41:51.785685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1705071320,"revision":2936,"compact-revision":-1} {"level":"info","ts":"2026-10-17T11:41:53.647718Z","caller":"traceutil/trace.go:171","msg":"trace[246384226] transaction","detail":"{read_only:false; response_revision:5755; number_of_response:1; }","duration":"164.481742ms","start":"2026-10-17T11:41:53.483202Z","end":"2026-10-17T11:41:53.647684Z","steps":["trace[246384226] 'process raft request' (duration: 164.279826ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:53.648032Z","caller":"traceutil/trace.go:171","msg":"trace[39628854] linearizableReadLoop","detail":"{readStateIndex:5975; appliedIndex:5975; }","duration":"158.693798ms","start":"2026-10-17T11:41:53.489293Z","end":"2026-10-17T11:41:53.647987Z","steps":["trace[39628854] 'read index received' (duration: 158.674328ms)","trace[39628854] 'applied index is now lower than readState.Index' (duration: 16.06µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:41:53.648372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.039948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-17T11:41:53.648416Z","caller":"traceutil/trace.go:171","msg":"trace[357881397] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5755; }","duration":"159.146871ms","start":"2026-10-17T11:41:53.489258Z","end":"2026-10-17T11:41:53.648405Z","steps":["trace[357881397] 'agreement among raft nodes before linearized reading' (duration: 158.903374ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:53.93382Z","caller":"traceutil/trace.go:171","msg":"trace[1155639579] linearizableReadLoop","detail":"{readStateIndex:5976; appliedIndex:5975; }","duration":"285.673045ms","start":"2026-10-17T11:41:53.648122Z","end":"2026-10-17T11:41:53.933795Z","steps":["trace[1155639579] 'read index received' (duration: 285.48748ms)","trace[1155639579] 'applied index is now lower than readState.Index' (duration: 184.165µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:53.933974Z","caller":"traceutil/trace.go:171","msg":"trace[1909393640] transaction","detail":"{read_only:false; response_revision:5756; number_of_response:1; }","duration":"365.636397ms","start":"2026-10-17T11:41:53.568325Z","end":"2026-10-17T11:41:53.933962Z","steps":["trace[1909393640] 'process raft request' (duration: 365.299777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:53.934096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:53.568276Z","time spent":"365.728809ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:41:53.934342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.06466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T11:41:53.93444Z","caller":"traceutil/trace.go:171","msg":"trace[1221076280] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:5756; }","duration":"371.240534ms","start":"2026-10-17T11:41:53.563178Z","end":"2026-10-17T11:41:53.934418Z","steps":["trace[1221076280] 'agreement among raft nodes before linearized reading' (duration: 371.003298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:53.934492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:53.563154Z","time spent":"371.326887ms","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":20,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:41:53.934486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.988161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-17T11:41:53.934614Z","caller":"traceutil/trace.go:171","msg":"trace[296733198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5756; }","duration":"187.206318ms","start":"2026-10-17T11:41:53.747378Z","end":"2026-10-17T11:41:53.934585Z","steps":["trace[296733198] 'agreement among raft nodes before linearized reading' (duration: 186.779716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:53.935116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.339033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-32740e04-1d6f-4aec-9b3e-310409f3dc55\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-10-17T11:41:53.935233Z","caller":"traceutil/trace.go:171","msg":"trace[763949599] range","detail":"{range_begin:/registry/persistentvolumes/pvc-32740e04-1d6f-4aec-9b3e-310409f3dc55; range_end:; response_count:1; response_revision:5756; }","duration":"280.588289ms","start":"2026-10-17T11:41:53.654618Z","end":"2026-10-17T11:41:53.935207Z","steps":["trace[763949599] 'agreement among raft nodes before linearized reading' (duration: 279.487839ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:54.080794Z","caller":"traceutil/trace.go:171","msg":"trace[1774721151] transaction","detail":"{read_only:false; response_revision:5757; number_of_response:1; }","duration":"140.913522ms","start":"2026-10-17T11:41:53.939852Z","end":"2026-10-17T11:41:54.080765Z","steps":["trace[1774721151] 'process raft request' (duration: 65.111919ms)","trace[1774721151] 'compare' (duration: 75.69111ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:54.109472Z","caller":"traceutil/trace.go:171","msg":"trace[1356913771] transaction","detail":"{read_only:false; response_revision:5758; number_of_response:1; }","duration":"168.631081ms","start":"2026-10-17T11:41:53.940816Z","end":"2026-10-17T11:41:54.109447Z","steps":["trace[1356913771] 'process raft request' (duration: 168.345342ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:46:51.709213Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5677} {"level":"info","ts":"2026-10-17T11:46:51.818332Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5677,"took":"106.516871ms","hash":57223628,"current-db-size-bytes":56291328,"current-db-size":"56 MB","current-db-size-in-use-bytes":26775552,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-17T11:46:51.818424Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":57223628,"revision":5677,"compact-revision":2936} {"level":"warn","ts":"2026-10-17T11:48:16.839589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.43513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-17T11:48:16.839696Z","caller":"traceutil/trace.go:171","msg":"trace[1246156711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8669; }","duration":"433.584114ms","start":"2026-10-17T11:48:16.406092Z","end":"2026-10-17T11:48:16.839676Z","steps":["trace[1246156711] 'range keys from in-memory index tree' (duration: 433.195184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:48:16.839723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:48:16.406078Z","time spent":"433.639115ms","remote":"127.0.0.1:33280","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":"warn","ts":"2026-10-17T11:48:16.839931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.97224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-17T11:48:16.839931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.816961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:48:16.840088Z","caller":"traceutil/trace.go:171","msg":"trace[1294397816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8669; }","duration":"508.040637ms","start":"2026-10-17T11:48:16.332024Z","end":"2026-10-17T11:48:16.840065Z","steps":["trace[1294397816] 'range keys from in-memory index tree' (duration: 507.684197ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:48:16.840089Z","caller":"traceutil/trace.go:171","msg":"trace[1183451415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8669; }","duration":"438.163836ms","start":"2026-10-17T11:48:16.401895Z","end":"2026-10-17T11:48:16.840059Z","steps":["trace[1183451415] 'range keys from in-memory index tree' (duration: 437.771544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:48:16.840144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:48:16.331973Z","time spent":"508.15532ms","remote":"127.0.0.1:33030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-17T11:48:16.840152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:48:16.401878Z","time spent":"438.260418ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-17T11:48:28.268483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.994127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:48:28.268524Z","caller":"traceutil/trace.go:171","msg":"trace[1675762170] transaction","detail":"{read_only:false; response_revision:8731; number_of_response:1; }","duration":"201.066231ms","start":"2026-10-17T11:48:28.067426Z","end":"2026-10-17T11:48:28.268492Z","steps":["trace[1675762170] 'process raft request' (duration: 176.123577ms)","trace[1675762170] 'compare' (duration: 24.663356ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:48:28.268575Z","caller":"traceutil/trace.go:171","msg":"trace[1266517557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8730; }","duration":"217.10887ms","start":"2026-10-17T11:48:28.051451Z","end":"2026-10-17T11:48:28.26856Z","steps":["trace[1266517557] 'range keys from in-memory index tree' (duration: 216.827673ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:48:28.339677Z","caller":"traceutil/trace.go:171","msg":"trace[368681471] linearizableReadLoop","detail":"{readStateIndex:9047; appliedIndex:9045; }","duration":"248.038872ms","start":"2026-10-17T11:48:28.091603Z","end":"2026-10-17T11:48:28.339641Z","steps":["trace[368681471] 'read index received' (duration: 151.971173ms)","trace[368681471] 'applied index is now lower than readState.Index' (duration: 96.066189ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:48:28.339764Z","caller":"traceutil/trace.go:171","msg":"trace[295030343] transaction","detail":"{read_only:false; response_revision:8732; number_of_response:1; }","duration":"270.138702ms","start":"2026-10-17T11:48:28.069568Z","end":"2026-10-17T11:48:28.339707Z","steps":["trace[295030343] 'process raft request' (duration: 269.916925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:48:28.339944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.315759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5bdf6bdbbf-qll67.18df4eaca1386f19\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-10-17T11:48:28.34002Z","caller":"traceutil/trace.go:171","msg":"trace[2076051876] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5bdf6bdbbf-qll67.18df4eaca1386f19; range_end:; response_count:1; response_revision:8732; }","duration":"248.435402ms","start":"2026-10-17T11:48:28.09155Z","end":"2026-10-17T11:48:28.339986Z","steps":["trace[2076051876] 'agreement among raft nodes before linearized reading' (duration: 248.247757ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:48:29.067349Z","caller":"traceutil/trace.go:171","msg":"trace[1110387459] transaction","detail":"{read_only:false; response_revision:8734; number_of_response:1; }","duration":"139.499517ms","start":"2026-10-17T11:48:28.927818Z","end":"2026-10-17T11:48:29.067318Z","steps":["trace[1110387459] 'process raft request' (duration: 139.281651ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:48:29.399662Z","caller":"traceutil/trace.go:171","msg":"trace[483137665] linearizableReadLoop","detail":"{readStateIndex:9050; appliedIndex:9049; }","duration":"220.296325ms","start":"2026-10-17T11:48:29.179328Z","end":"2026-10-17T11:48:29.399624Z","steps":["trace[483137665] 'read index received' (duration: 205.142618ms)","trace[483137665] 'applied index is now lower than readState.Index' (duration: 15.151487ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:48:29.399762Z","caller":"traceutil/trace.go:171","msg":"trace[1112009421] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"471.124078ms","start":"2026-10-17T11:48:28.928587Z","end":"2026-10-17T11:48:29.399711Z","steps":["trace[1112009421] 'process raft request' (duration: 455.952031ms)","trace[1112009421] 'compare' (duration: 14.730956ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:48:29.399911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.553041ms","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-10-17T11:48:29.399947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:48:28.928566Z","time spent":"471.285113ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:48:29.399958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.467077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-17T11:48:29.399973Z","caller":"traceutil/trace.go:171","msg":"trace[1400198568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8735; }","duration":"220.685955ms","start":"2026-10-17T11:48:29.179272Z","end":"2026-10-17T11:48:29.399958Z","steps":["trace[1400198568] 'agreement among raft nodes before linearized reading' (duration: 220.48726ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:48:29.399973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.486052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:48:29.400103Z","caller":"traceutil/trace.go:171","msg":"trace[1110284308] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:8735; }","duration":"140.679817ms","start":"2026-10-17T11:48:29.259397Z","end":"2026-10-17T11:48:29.400077Z","steps":["trace[1110284308] 'agreement among raft nodes before linearized reading' (duration: 140.440721ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:48:29.400037Z","caller":"traceutil/trace.go:171","msg":"trace[542466965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8735; }","duration":"215.55377ms","start":"2026-10-17T11:48:29.18444Z","end":"2026-10-17T11:48:29.399993Z","steps":["trace[542466965] 'agreement among raft nodes before linearized reading' (duration: 215.384865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:48:29.400261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.592317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T11:48:29.400316Z","caller":"traceutil/trace.go:171","msg":"trace[1761352518] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8735; }","duration":"179.68985ms","start":"2026-10-17T11:48:29.220612Z","end":"2026-10-17T11:48:29.400302Z","steps":["trace[1761352518] 'agreement among raft nodes before linearized reading' (duration: 179.452974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:48:30.598948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.121645ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-17T11:48:43.168162Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ac.wal"} {"level":"info","ts":"2026-10-17T11:49:00.137831Z","caller":"traceutil/trace.go:171","msg":"trace[1350078556] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"388.818491ms","start":"2026-10-17T11:48:59.748981Z","end":"2026-10-17T11:49:00.137799Z","steps":["trace[1350078556] 'process raft request' (duration: 388.642366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:49:00.138052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:48:59.748962Z","time spent":"388.971004ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:49:00.138655Z","caller":"traceutil/trace.go:171","msg":"trace[2142799382] linearizableReadLoop","detail":"{readStateIndex:9277; appliedIndex:9277; }","duration":"378.814612ms","start":"2026-10-17T11:48:59.759825Z","end":"2026-10-17T11:49:00.13864Z","steps":["trace[2142799382] 'read index received' (duration: 378.807682ms)","trace[2142799382] 'applied index is now lower than readState.Index' (duration: 5.76µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:49:00.139213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.379938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228866"} {"level":"info","ts":"2026-10-17T11:49:00.13926Z","caller":"traceutil/trace.go:171","msg":"trace[951079707] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8954; }","duration":"379.47544ms","start":"2026-10-17T11:48:59.759772Z","end":"2026-10-17T11:49:00.139247Z","steps":["trace[951079707] 'agreement among raft nodes before linearized reading' (duration: 378.946476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:49:00.139291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:48:59.759746Z","time spent":"379.536082ms","remote":"127.0.0.1:33206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":228889,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-10-17T11:49:00.174732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.983085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:49:00.174826Z","caller":"traceutil/trace.go:171","msg":"trace[349763395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8955; }","duration":"123.128459ms","start":"2026-10-17T11:49:00.051677Z","end":"2026-10-17T11:49:00.174805Z","steps":["trace[349763395] 'agreement among raft nodes before linearized reading' (duration: 122.957274ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:49:00.175251Z","caller":"traceutil/trace.go:171","msg":"trace[1145340043] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"137.441981ms","start":"2026-10-17T11:49:00.037793Z","end":"2026-10-17T11:49:00.175235Z","steps":["trace[1145340043] 'process raft request' (duration: 136.699831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:49:00.175489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.672668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-17T11:49:00.175527Z","caller":"traceutil/trace.go:171","msg":"trace[1929068963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8955; }","duration":"107.75662ms","start":"2026-10-17T11:49:00.06776Z","end":"2026-10-17T11:49:00.175517Z","steps":["trace[1929068963] 'agreement among raft nodes before linearized reading' (duration: 107.622987ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:49:00.304293Z","caller":"traceutil/trace.go:171","msg":"trace[303980206] transaction","detail":"{read_only:false; response_revision:8956; number_of_response:1; }","duration":"109.084124ms","start":"2026-10-17T11:49:00.195184Z","end":"2026-10-17T11:49:00.304268Z","steps":["trace[303980206] 'process raft request' (duration: 44.002233ms)","trace[303980206] 'compare' (duration: 64.845455ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:49:02.633735Z","caller":"traceutil/trace.go:171","msg":"trace[978022885] transaction","detail":"{read_only:false; response_revision:8966; number_of_response:1; }","duration":"183.152968ms","start":"2026-10-17T11:49:02.450525Z","end":"2026-10-17T11:49:02.633678Z","steps":["trace[978022885] 'process raft request' (duration: 142.980524ms)","trace[978022885] 'compare' (duration: 39.861936ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:49:02.646474Z","caller":"traceutil/trace.go:171","msg":"trace[1507903338] transaction","detail":"{read_only:false; response_revision:8967; number_of_response:1; }","duration":"180.548651ms","start":"2026-10-17T11:49:02.465907Z","end":"2026-10-17T11:49:02.646455Z","steps":["trace[1507903338] 'process raft request' (duration: 180.420248ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:49:09.838648Z","caller":"traceutil/trace.go:171","msg":"trace[2025994385] linearizableReadLoop","detail":"{readStateIndex:9332; appliedIndex:9331; }","duration":"149.170815ms","start":"2026-10-17T11:49:09.689445Z","end":"2026-10-17T11:49:09.838616Z","steps":["trace[2025994385] 'read index received' (duration: 78.249123ms)","trace[2025994385] 'applied index is now lower than readState.Index' (duration: 70.920352ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:49:09.838743Z","caller":"traceutil/trace.go:171","msg":"trace[1531651838] transaction","detail":"{read_only:false; response_revision:9007; number_of_response:1; }","duration":"154.441172ms","start":"2026-10-17T11:49:09.684259Z","end":"2026-10-17T11:49:09.8387Z","steps":["trace[1531651838] 'process raft request' (duration: 83.395687ms)","trace[1531651838] 'compare' (duration: 70.82748ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:49:09.838979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.513523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-17T11:49:09.839088Z","caller":"traceutil/trace.go:171","msg":"trace[2076813443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9007; }","duration":"149.673558ms","start":"2026-10-17T11:49:09.689398Z","end":"2026-10-17T11:49:09.839072Z","steps":["trace[2076813443] 'agreement among raft nodes before linearized reading' (duration: 149.36248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:49:10.558669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.745925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:49:10.559038Z","caller":"traceutil/trace.go:171","msg":"trace[1573517238] transaction","detail":"{read_only:false; response_revision:9010; number_of_response:1; }","duration":"321.898643ms","start":"2026-10-17T11:49:10.237048Z","end":"2026-10-17T11:49:10.558947Z","steps":["trace[1573517238] 'process raft request' (duration: 192.763788ms)","trace[1573517238] 'compare' (duration: 128.383156ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:49:10.559098Z","caller":"traceutil/trace.go:171","msg":"trace[968343243] linearizableReadLoop","detail":"{readStateIndex:9335; appliedIndex:9333; }","duration":"297.014066ms","start":"2026-10-17T11:49:10.262046Z","end":"2026-10-17T11:49:10.55906Z","steps":["trace[968343243] 'read index received' (duration: 39.757993ms)","trace[968343243] 'applied index is now lower than readState.Index' (duration: 257.251873ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:49:10.559247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:49:10.236978Z","time spent":"322.1472ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:49:10.710389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.586938ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-17T11:49:10.710485Z","caller":"traceutil/trace.go:171","msg":"trace[1565912218] linearizableReadLoop","detail":"{readStateIndex:9336; appliedIndex:9335; }","duration":"151.282011ms","start":"2026-10-17T11:49:10.559178Z","end":"2026-10-17T11:49:10.71046Z","steps":["trace[1565912218] 'read index received' (duration: 72.253µs)","trace[1565912218] 'applied index is now lower than readState.Index' (duration: 151.208178ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:49:10.710795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.726369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cvvhq\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-10-17T11:49:10.710956Z","caller":"traceutil/trace.go:171","msg":"trace[1012160618] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cvvhq; range_end:; response_count:1; response_revision:9010; }","duration":"448.949795ms","start":"2026-10-17T11:49:10.261938Z","end":"2026-10-17T11:49:10.710887Z","steps":["trace[1012160618] 'agreement among raft nodes before linearized reading' (duration: 297.265873ms)","trace[1012160618] 'range keys from in-memory index tree' (duration: 151.352073ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:49:10.711049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:49:10.261911Z","time spent":"449.119328ms","remote":"127.0.0.1:33206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6541,"request content":"key:\"/registry/pods/openstack/keepalived-cvvhq\" "} {"level":"warn","ts":"2026-10-17T11:49:10.711533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.53658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-17T11:49:10.711591Z","caller":"traceutil/trace.go:171","msg":"trace[1269834237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9010; }","duration":"337.662113ms","start":"2026-10-17T11:49:10.373915Z","end":"2026-10-17T11:49:10.711577Z","steps":["trace[1269834237] 'agreement among raft nodes before linearized reading' (duration: 337.481449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:49:10.711623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:49:10.373883Z","time spent":"337.732265ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-17T11:49:10.789282Z","caller":"traceutil/trace.go:171","msg":"trace[273777771] transaction","detail":"{read_only:false; response_revision:9012; number_of_response:1; }","duration":"116.145958ms","start":"2026-10-17T11:49:10.673115Z","end":"2026-10-17T11:49:10.789261Z","steps":["trace[273777771] 'process raft request' (duration: 115.927102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:49:10.789735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.495112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-17T11:49:10.789834Z","caller":"traceutil/trace.go:171","msg":"trace[1190463165] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9013; }","duration":"152.640736ms","start":"2026-10-17T11:49:10.637171Z","end":"2026-10-17T11:49:10.789812Z","steps":["trace[1190463165] 'agreement among raft nodes before linearized reading' (duration: 152.376909ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:49:14.463125Z","caller":"traceutil/trace.go:171","msg":"trace[292341448] transaction","detail":"{read_only:false; response_revision:9039; number_of_response:1; }","duration":"144.913095ms","start":"2026-10-17T11:49:14.318187Z","end":"2026-10-17T11:49:14.4631Z","steps":["trace[292341448] 'process raft request' (duration: 144.741281ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:50:13.722308Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fbe9b088d8de907a","local-member-applied-index":10003,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:50:13.730155Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10003} {"level":"info","ts":"2026-10-17T11:50:13.730239Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5003} {"level":"info","ts":"2026-10-17T11:50:27.961006Z","caller":"traceutil/trace.go:171","msg":"trace[2069770616] transaction","detail":"{read_only:false; response_revision:9799; number_of_response:1; }","duration":"132.949373ms","start":"2026-10-17T11:50:27.828024Z","end":"2026-10-17T11:50:27.960973Z","steps":["trace[2069770616] 'process raft request' (duration: 132.740338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:50:42.180758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.465573ms","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-10-17T11:50:42.180892Z","caller":"traceutil/trace.go:171","msg":"trace[1138355876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9878; }","duration":"144.657998ms","start":"2026-10-17T11:50:42.036205Z","end":"2026-10-17T11:50:42.180863Z","steps":["trace[1138355876] 'range keys from in-memory index tree' (duration: 144.193995ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:51:51.717702Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7992} {"level":"info","ts":"2026-10-17T11:51:51.807719Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7992,"took":"86.844497ms","hash":2733831026,"current-db-size-bytes":56291328,"current-db-size":"56 MB","current-db-size-in-use-bytes":24780800,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-17T11:51:51.807815Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2733831026,"revision":7992,"compact-revision":5677} {"level":"info","ts":"2026-10-17T11:56:24.682937Z","caller":"traceutil/trace.go:171","msg":"trace[1498377800] transaction","detail":"{read_only:false; response_revision:12333; number_of_response:1; }","duration":"105.464763ms","start":"2026-10-17T11:56:24.577418Z","end":"2026-10-17T11:56:24.682882Z","steps":["trace[1498377800] 'process raft request' (duration: 52.437069ms)","trace[1498377800] 'compare' (duration: 52.87284ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:56:51.725334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10358} {"level":"info","ts":"2026-10-17T11:56:51.817582Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10358,"took":"89.605835ms","hash":611284550,"current-db-size-bytes":56291328,"current-db-size":"56 MB","current-db-size-in-use-bytes":26128384,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-17T11:56:51.817668Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":611284550,"revision":10358,"compact-revision":7992} {"level":"info","ts":"2026-10-17T12:01:51.785471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12525} {"level":"info","ts":"2026-10-17T12:01:51.87601Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12525,"took":"87.762861ms","hash":3688972262,"current-db-size-bytes":56291328,"current-db-size":"56 MB","current-db-size-in-use-bytes":27668480,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-17T12:01:51.876089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3688972262,"revision":12525,"compact-revision":10358} {"level":"info","ts":"2026-10-17T12:02:24.306364Z","caller":"traceutil/trace.go:171","msg":"trace[545550942] transaction","detail":"{read_only:false; response_revision:14805; number_of_response:1; }","duration":"228.473434ms","start":"2026-10-17T12:02:24.077842Z","end":"2026-10-17T12:02:24.306315Z","steps":["trace[545550942] 'process raft request' (duration: 228.122305ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:02:45.970187Z","caller":"traceutil/trace.go:171","msg":"trace[835055365] transaction","detail":"{read_only:false; response_revision:14921; number_of_response:1; }","duration":"141.307636ms","start":"2026-10-17T12:02:45.828851Z","end":"2026-10-17T12:02:45.970159Z","steps":["trace[835055365] 'process raft request' (duration: 141.118411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:03:02.845071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.794696ms","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-10-17T12:03:02.846011Z","caller":"traceutil/trace.go:171","msg":"trace[2097866550] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:15025; }","duration":"156.807172ms","start":"2026-10-17T12:03:02.689174Z","end":"2026-10-17T12:03:02.845982Z","steps":["trace[2097866550] 'count revisions from in-memory index tree' (duration: 155.694094ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:03:03.82083Z","caller":"traceutil/trace.go:171","msg":"trace[415083707] transaction","detail":"{read_only:false; response_revision:15027; number_of_response:1; }","duration":"154.027469ms","start":"2026-10-17T12:03:03.666735Z","end":"2026-10-17T12:03:03.820763Z","steps":["trace[415083707] 'process raft request' (duration: 153.762042ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:03:03.867639Z","caller":"traceutil/trace.go:171","msg":"trace[338902562] transaction","detail":"{read_only:false; response_revision:15028; number_of_response:1; }","duration":"101.858211ms","start":"2026-10-17T12:03:03.765755Z","end":"2026-10-17T12:03:03.867613Z","steps":["trace[338902562] 'process raft request' (duration: 100.390262ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:03:14.725735Z","caller":"traceutil/trace.go:171","msg":"trace[834951001] transaction","detail":"{read_only:false; response_revision:15202; number_of_response:1; }","duration":"149.981893ms","start":"2026-10-17T12:03:14.575713Z","end":"2026-10-17T12:03:14.725695Z","steps":["trace[834951001] 'process raft request' (duration: 149.721016ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:03:14.959109Z","caller":"traceutil/trace.go:171","msg":"trace[552769076] linearizableReadLoop","detail":"{readStateIndex:15732; appliedIndex:15730; }","duration":"317.887498ms","start":"2026-10-17T12:03:14.641189Z","end":"2026-10-17T12:03:14.959077Z","steps":["trace[552769076] 'read index received' (duration: 84.209855ms)","trace[552769076] 'applied index is now lower than readState.Index' (duration: 233.675963ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:03:14.959257Z","caller":"traceutil/trace.go:171","msg":"trace[356812325] transaction","detail":"{read_only:false; response_revision:15203; number_of_response:1; }","duration":"348.978279ms","start":"2026-10-17T12:03:14.610264Z","end":"2026-10-17T12:03:14.959242Z","steps":["trace[356812325] 'process raft request' (duration: 295.25466ms)","trace[356812325] 'compare' (duration: 53.098243ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T12:03:14.959353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:03:14.610236Z","time spent":"349.047711ms","remote":"127.0.0.1:33100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-17T12:03:14.959567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.36976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-17T12:03:14.95964Z","caller":"traceutil/trace.go:171","msg":"trace[1598969670] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15203; }","duration":"318.492443ms","start":"2026-10-17T12:03:14.641132Z","end":"2026-10-17T12:03:14.959624Z","steps":["trace[1598969670] 'agreement among raft nodes before linearized reading' (duration: 318.249497ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:03:14.959671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:03:14.64111Z","time spent":"318.553335ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-17T12:03:14.959647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.437623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-17T12:03:14.959736Z","caller":"traceutil/trace.go:171","msg":"trace[851566475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15203; }","duration":"315.553696ms","start":"2026-10-17T12:03:14.644157Z","end":"2026-10-17T12:03:14.959711Z","steps":["trace[851566475] 'agreement among raft nodes before linearized reading' (duration: 315.240317ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:03:14.959776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.840669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-17T12:03:14.959789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:03:14.644147Z","time spent":"315.629338ms","remote":"127.0.0.1:33280","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-10-17T12:03:14.959812Z","caller":"traceutil/trace.go:171","msg":"trace[947308012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15203; }","duration":"308.921411ms","start":"2026-10-17T12:03:14.65088Z","end":"2026-10-17T12:03:14.959802Z","steps":["trace[947308012] 'agreement among raft nodes before linearized reading' (duration: 308.694605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:03:14.95984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:03:14.650871Z","time spent":"308.962263ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-17T12:03:14.959644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.211432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/ovn-ovsdb-sb\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-10-17T12:03:14.959915Z","caller":"traceutil/trace.go:171","msg":"trace[997445251] range","detail":"{range_begin:/registry/services/endpoints/openstack/ovn-ovsdb-sb; range_end:; response_count:1; response_revision:15203; }","duration":"244.518259ms","start":"2026-10-17T12:03:14.715381Z","end":"2026-10-17T12:03:14.959899Z","steps":["trace[997445251] 'agreement among raft nodes before linearized reading' (duration: 244.103109ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:03:14.959974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.6852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/ovn-ovsdb-nb\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-10-17T12:03:14.960087Z","caller":"traceutil/trace.go:171","msg":"trace[1073272639] range","detail":"{range_begin:/registry/services/endpoints/openstack/ovn-ovsdb-nb; range_end:; response_count:1; response_revision:15203; }","duration":"245.859575ms","start":"2026-10-17T12:03:14.714201Z","end":"2026-10-17T12:03:14.960061Z","steps":["trace[1073272639] 'agreement among raft nodes before linearized reading' (duration: 245.234288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:03:14.960107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.381216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-17T12:03:14.960261Z","caller":"traceutil/trace.go:171","msg":"trace[762264417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15203; }","duration":"314.572262ms","start":"2026-10-17T12:03:14.64564Z","end":"2026-10-17T12:03:14.960213Z","steps":["trace[762264417] 'agreement among raft nodes before linearized reading' (duration: 313.993656ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:03:14.960344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:03:14.645614Z","time spent":"314.716775ms","remote":"127.0.0.1:33280","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-10-17T12:04:22.724881Z","caller":"traceutil/trace.go:171","msg":"trace[72481300] transaction","detail":"{read_only:false; response_revision:16123; number_of_response:1; }","duration":"100.398222ms","start":"2026-10-17T12:04:22.624445Z","end":"2026-10-17T12:04:22.724843Z","steps":["trace[72481300] 'process raft request' (duration: 100.043073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:04:56.76912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.374074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T12:04:56.769381Z","caller":"traceutil/trace.go:171","msg":"trace[2021880532] transaction","detail":"{read_only:false; response_revision:16302; number_of_response:1; }","duration":"200.037674ms","start":"2026-10-17T12:04:56.569308Z","end":"2026-10-17T12:04:56.769345Z","steps":["trace[2021880532] 'process raft request' (duration: 80.333011ms)","trace[2021880532] 'compare' (duration: 119.22542ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:04:56.769419Z","caller":"traceutil/trace.go:171","msg":"trace[1245395078] linearizableReadLoop","detail":"{readStateIndex:16870; appliedIndex:16867; }","duration":"180.984192ms","start":"2026-10-17T12:04:56.588413Z","end":"2026-10-17T12:04:56.769397Z","steps":["trace[1245395078] 'read index received' (duration: 61.242468ms)","trace[1245395078] 'applied index is now lower than readState.Index' (duration: 119.740544ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:04:56.769694Z","caller":"traceutil/trace.go:171","msg":"trace[258105097] transaction","detail":"{read_only:false; response_revision:16304; number_of_response:1; }","duration":"196.714686ms","start":"2026-10-17T12:04:56.572958Z","end":"2026-10-17T12:04:56.769672Z","steps":["trace[258105097] 'process raft request' (duration: 196.388488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:04:56.769722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.28908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T12:04:56.769761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.840257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-17T12:04:56.769804Z","caller":"traceutil/trace.go:171","msg":"trace[478116436] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16304; }","duration":"180.923529ms","start":"2026-10-17T12:04:56.588869Z","end":"2026-10-17T12:04:56.769792Z","steps":["trace[478116436] 'agreement among raft nodes before linearized reading' (duration: 180.775156ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:04:56.769799Z","caller":"traceutil/trace.go:171","msg":"trace[2010622120] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16304; }","duration":"181.423303ms","start":"2026-10-17T12:04:56.588357Z","end":"2026-10-17T12:04:56.769781Z","steps":["trace[2010622120] 'agreement among raft nodes before linearized reading' (duration: 181.268329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:04:56.769886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.40011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-17T12:04:56.769965Z","caller":"traceutil/trace.go:171","msg":"trace[1781805140] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16304; }","duration":"176.514093ms","start":"2026-10-17T12:04:56.593429Z","end":"2026-10-17T12:04:56.769943Z","steps":["trace[1781805140] 'agreement among raft nodes before linearized reading' (duration: 176.293848ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:04:56.770212Z","caller":"traceutil/trace.go:171","msg":"trace[1448982188] transaction","detail":"{read_only:false; response_revision:16303; number_of_response:1; }","duration":"200.161288ms","start":"2026-10-17T12:04:56.57003Z","end":"2026-10-17T12:04:56.770191Z","steps":["trace[1448982188] 'process raft request' (duration: 199.234214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:04:57.399553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.427413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cvvhq\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-10-17T12:04:57.399664Z","caller":"traceutil/trace.go:171","msg":"trace[1420590242] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cvvhq; range_end:; response_count:1; response_revision:16305; }","duration":"142.582867ms","start":"2026-10-17T12:04:57.257059Z","end":"2026-10-17T12:04:57.399642Z","steps":["trace[1420590242] 'range keys from in-memory index tree' (duration: 142.244558ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:04:57.787232Z","caller":"traceutil/trace.go:171","msg":"trace[497215573] linearizableReadLoop","detail":"{readStateIndex:16872; appliedIndex:16871; }","duration":"193.4524ms","start":"2026-10-17T12:04:57.593756Z","end":"2026-10-17T12:04:57.787208Z","steps":["trace[497215573] 'read index received' (duration: 193.245285ms)","trace[497215573] 'applied index is now lower than readState.Index' (duration: 206.295µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:04:57.787364Z","caller":"traceutil/trace.go:171","msg":"trace[1016383241] transaction","detail":"{read_only:false; response_revision:16306; number_of_response:1; }","duration":"238.560692ms","start":"2026-10-17T12:04:57.548768Z","end":"2026-10-17T12:04:57.787329Z","steps":["trace[1016383241] 'process raft request' (duration: 238.288755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:04:57.787477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.691226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-17T12:04:57.787517Z","caller":"traceutil/trace.go:171","msg":"trace[549947458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16306; }","duration":"193.754458ms","start":"2026-10-17T12:04:57.593752Z","end":"2026-10-17T12:04:57.787507Z","steps":["trace[549947458] 'agreement among raft nodes before linearized reading' (duration: 193.589724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:04:57.787621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.10402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-17T12:04:57.787698Z","caller":"traceutil/trace.go:171","msg":"trace[39025739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16306; }","duration":"188.239153ms","start":"2026-10-17T12:04:57.599441Z","end":"2026-10-17T12:04:57.787681Z","steps":["trace[39025739] 'agreement among raft nodes before linearized reading' (duration: 187.957055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:04:57.787939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.904133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-17T12:04:57.788038Z","caller":"traceutil/trace.go:171","msg":"trace[1516990765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16306; }","duration":"183.033747ms","start":"2026-10-17T12:04:57.604988Z","end":"2026-10-17T12:04:57.788022Z","steps":["trace[1516990765] 'agreement among raft nodes before linearized reading' (duration: 182.734159ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:06:51.793676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14562} {"level":"info","ts":"2026-10-17T12:06:51.887725Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14562,"took":"89.062982ms","hash":618965826,"current-db-size-bytes":56291328,"current-db-size":"56 MB","current-db-size-in-use-bytes":31498240,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T12:06:51.887812Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":618965826,"revision":14562,"compact-revision":12525} {"level":"info","ts":"2026-10-17T12:10:33.749742Z","caller":"traceutil/trace.go:171","msg":"trace[736570417] linearizableReadLoop","detail":"{readStateIndex:19463; appliedIndex:19461; }","duration":"119.537564ms","start":"2026-10-17T12:10:33.630177Z","end":"2026-10-17T12:10:33.749715Z","steps":["trace[736570417] 'read index received' (duration: 16.678101ms)","trace[736570417] 'applied index is now lower than readState.Index' (duration: 102.858393ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:10:33.749807Z","caller":"traceutil/trace.go:171","msg":"trace[823788061] transaction","detail":"{read_only:false; response_revision:18816; number_of_response:1; }","duration":"128.46911ms","start":"2026-10-17T12:10:33.621315Z","end":"2026-10-17T12:10:33.749784Z","steps":["trace[823788061] 'process raft request' (duration: 64.843666ms)","trace[823788061] 'compare' (duration: 63.403858ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T12:10:33.749995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.795511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-17T12:10:33.750028Z","caller":"traceutil/trace.go:171","msg":"trace[1941562930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18816; }","duration":"119.875673ms","start":"2026-10-17T12:10:33.630142Z","end":"2026-10-17T12:10:33.750017Z","steps":["trace[1941562930] 'agreement among raft nodes before linearized reading' (duration: 119.729679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:34.054491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.608827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T12:10:34.054687Z","caller":"traceutil/trace.go:171","msg":"trace[593621420] transaction","detail":"{read_only:false; response_revision:18817; number_of_response:1; }","duration":"300.32996ms","start":"2026-10-17T12:10:33.754296Z","end":"2026-10-17T12:10:34.054626Z","steps":["trace[593621420] 'process raft request' (duration: 148.793598ms)","trace[593621420] 'compare' (duration: 150.452642ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:10:34.054838Z","caller":"traceutil/trace.go:171","msg":"trace[135842333] transaction","detail":"{read_only:false; response_revision:18818; number_of_response:1; }","duration":"185.10672ms","start":"2026-10-17T12:10:33.869709Z","end":"2026-10-17T12:10:34.054815Z","steps":["trace[135842333] 'process raft request' (duration: 184.958756ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:34.054867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:10:33.754252Z","time spent":"300.518814ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T12:10:34.055118Z","caller":"traceutil/trace.go:171","msg":"trace[944698810] linearizableReadLoop","detail":"{readStateIndex:19464; appliedIndex:19463; }","duration":"298.425039ms","start":"2026-10-17T12:10:33.75668Z","end":"2026-10-17T12:10:34.055105Z","steps":["trace[944698810] 'read index received' (duration: 146.347933ms)","trace[944698810] 'applied index is now lower than readState.Index' (duration: 152.075046ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T12:10:34.056634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.97572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:97 size:955374"} {"level":"info","ts":"2026-10-17T12:10:34.056712Z","caller":"traceutil/trace.go:171","msg":"trace[904608042] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:97; response_revision:18818; }","duration":"300.103863ms","start":"2026-10-17T12:10:33.756595Z","end":"2026-10-17T12:10:34.056699Z","steps":["trace[904608042] 'agreement among raft nodes before linearized reading' (duration: 298.578693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:34.056746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:10:33.756567Z","time spent":"300.167695ms","remote":"127.0.0.1:33206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":97,"response size":955397,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-10-17T12:10:34.106054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.022521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T12:10:34.106251Z","caller":"traceutil/trace.go:171","msg":"trace[1940313231] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:18821; }","duration":"249.295108ms","start":"2026-10-17T12:10:33.856931Z","end":"2026-10-17T12:10:34.106226Z","steps":["trace[1940313231] 'agreement among raft nodes before linearized reading' (duration: 248.99453ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:34.106695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.135348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T12:10:34.106773Z","caller":"traceutil/trace.go:171","msg":"trace[2032415121] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:18821; }","duration":"126.2148ms","start":"2026-10-17T12:10:33.980536Z","end":"2026-10-17T12:10:34.106751Z","steps":["trace[2032415121] 'agreement among raft nodes before linearized reading' (duration: 126.069437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:35.861534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.377103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-17T12:10:35.861619Z","caller":"traceutil/trace.go:171","msg":"trace[94291906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18842; }","duration":"107.517947ms","start":"2026-10-17T12:10:35.754082Z","end":"2026-10-17T12:10:35.8616Z","steps":["trace[94291906] 'range keys from in-memory index tree' (duration: 107.125735ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:10:38.767702Z","caller":"traceutil/trace.go:171","msg":"trace[1498106499] linearizableReadLoop","detail":"{readStateIndex:19520; appliedIndex:19519; }","duration":"186.709061ms","start":"2026-10-17T12:10:38.580969Z","end":"2026-10-17T12:10:38.767678Z","steps":["trace[1498106499] 'read index received' (duration: 186.66057ms)","trace[1498106499] 'applied index is now lower than readState.Index' (duration: 47.271µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:10:38.767832Z","caller":"traceutil/trace.go:171","msg":"trace[1110328972] transaction","detail":"{read_only:false; response_revision:18872; number_of_response:1; }","duration":"294.577816ms","start":"2026-10-17T12:10:38.473244Z","end":"2026-10-17T12:10:38.767822Z","steps":["trace[1110328972] 'process raft request' (duration: 294.261278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:38.76809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.104042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-phz95\" ","response":"range_response_count:1 size:20752"} {"level":"info","ts":"2026-10-17T12:10:38.768128Z","caller":"traceutil/trace.go:171","msg":"trace[757517540] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-phz95; range_end:; response_count:1; response_revision:18872; }","duration":"187.195965ms","start":"2026-10-17T12:10:38.580923Z","end":"2026-10-17T12:10:38.768119Z","steps":["trace[757517540] 'agreement among raft nodes before linearized reading' (duration: 187.032471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:39.234563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.630574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T12:10:39.234687Z","caller":"traceutil/trace.go:171","msg":"trace[619468330] linearizableReadLoop","detail":"{readStateIndex:19521; appliedIndex:19520; }","duration":"466.852227ms","start":"2026-10-17T12:10:38.767791Z","end":"2026-10-17T12:10:39.234643Z","steps":["trace[619468330] 'read index received' (duration: 154.058308ms)","trace[619468330] 'applied index is now lower than readState.Index' (duration: 312.792839ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T12:10:39.234804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.951314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T12:10:39.234842Z","caller":"traceutil/trace.go:171","msg":"trace[1931048936] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:18873; }","duration":"542.030036ms","start":"2026-10-17T12:10:38.692799Z","end":"2026-10-17T12:10:39.23483Z","steps":["trace[1931048936] 'agreement among raft nodes before linearized reading' (duration: 541.936833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:39.234873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:10:38.69278Z","time spent":"542.083228ms","remote":"127.0.0.1:48362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"info","ts":"2026-10-17T12:10:39.23485Z","caller":"traceutil/trace.go:171","msg":"trace[734581629] transaction","detail":"{read_only:false; response_revision:18873; number_of_response:1; }","duration":"630.921729ms","start":"2026-10-17T12:10:38.603842Z","end":"2026-10-17T12:10:39.234764Z","steps":["trace[734581629] 'process raft request' (duration: 317.994897ms)","trace[734581629] 'compare' (duration: 312.410748ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T12:10:39.235121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:10:38.603828Z","time spent":"631.151096ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T12:10:39.297988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.539114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-17T12:10:39.29806Z","caller":"traceutil/trace.go:171","msg":"trace[1993201578] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:18874; }","duration":"453.646036ms","start":"2026-10-17T12:10:38.844393Z","end":"2026-10-17T12:10:39.298039Z","steps":["trace[1993201578] 'agreement among raft nodes before linearized reading' (duration: 453.447221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:39.298092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:10:38.84437Z","time spent":"453.713739ms","remote":"127.0.0.1:33134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-10-17T12:10:39.298216Z","caller":"traceutil/trace.go:171","msg":"trace[529119277] transaction","detail":"{read_only:false; response_revision:18874; number_of_response:1; }","duration":"510.162183ms","start":"2026-10-17T12:10:38.788037Z","end":"2026-10-17T12:10:39.298199Z","steps":["trace[529119277] 'process raft request' (duration: 509.69866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:39.2983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:10:38.788011Z","time spent":"510.242474ms","remote":"127.0.0.1:33206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20867,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T12:10:39.298355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.124241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-7zrf6\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-10-17T12:10:39.29839Z","caller":"traceutil/trace.go:171","msg":"trace[442094023] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-7zrf6; range_end:; response_count:1; response_revision:18874; }","duration":"278.216153ms","start":"2026-10-17T12:10:39.020165Z","end":"2026-10-17T12:10:39.298381Z","steps":["trace[442094023] 'agreement among raft nodes before linearized reading' (duration: 278.09862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:39.298495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.175948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-17T12:10:39.298534Z","caller":"traceutil/trace.go:171","msg":"trace[1640750891] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:18874; }","duration":"115.309822ms","start":"2026-10-17T12:10:39.183214Z","end":"2026-10-17T12:10:39.298524Z","steps":["trace[1640750891] 'agreement among raft nodes before linearized reading' (duration: 115.209279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:39.298584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.351532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-10-17T12:10:39.298633Z","caller":"traceutil/trace.go:171","msg":"trace[126454209] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:18874; }","duration":"404.424954ms","start":"2026-10-17T12:10:38.894198Z","end":"2026-10-17T12:10:39.298623Z","steps":["trace[126454209] 'agreement among raft nodes before linearized reading' (duration: 404.320611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:39.298686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:10:38.894185Z","time spent":"404.494016ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-17T12:10:39.298837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.63018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T12:10:39.298871Z","caller":"traceutil/trace.go:171","msg":"trace[1244586351] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:0; response_revision:18874; }","duration":"404.704182ms","start":"2026-10-17T12:10:38.894156Z","end":"2026-10-17T12:10:39.29886Z","steps":["trace[1244586351] 'agreement among raft nodes before linearized reading' (duration: 404.656141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:10:39.298925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:10:38.894128Z","time spent":"404.765844ms","remote":"127.0.0.1:35494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" "} {"level":"warn","ts":"2026-10-17T12:10:39.298001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.596467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T12:10:39.299065Z","caller":"traceutil/trace.go:171","msg":"trace[949250459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18874; }","duration":"247.702967ms","start":"2026-10-17T12:10:39.051353Z","end":"2026-10-17T12:10:39.299056Z","steps":["trace[949250459] 'agreement among raft nodes before linearized reading' (duration: 246.603317ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:11:15.279219Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fbe9b088d8de907a","local-member-applied-index":20004,"local-member-snapshot-index":10003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T12:11:15.286701Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20004} {"level":"info","ts":"2026-10-17T12:11:15.286784Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15004} {"level":"info","ts":"2026-10-17T12:11:51.801529Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17057} {"level":"info","ts":"2026-10-17T12:11:51.904353Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17057,"took":"98.982193ms","hash":360179830,"current-db-size-bytes":56291328,"current-db-size":"56 MB","current-db-size-in-use-bytes":34299904,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-17T12:11:51.904442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":360179830,"revision":17057,"compact-revision":14562} {"level":"info","ts":"2026-10-17T12:15:05.594174Z","caller":"traceutil/trace.go:171","msg":"trace[375672127] transaction","detail":"{read_only:false; response_revision:21089; number_of_response:1; }","duration":"197.140796ms","start":"2026-10-17T12:15:05.396997Z","end":"2026-10-17T12:15:05.594138Z","steps":["trace[375672127] 'process raft request' (duration: 196.88903ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:15:05.894564Z","caller":"traceutil/trace.go:171","msg":"trace[1875128212] transaction","detail":"{read_only:false; response_revision:21092; number_of_response:1; }","duration":"195.080152ms","start":"2026-10-17T12:15:05.699413Z","end":"2026-10-17T12:15:05.894493Z","steps":["trace[1875128212] 'process raft request' (duration: 193.429599ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:16:16.581861Z","caller":"traceutil/trace.go:171","msg":"trace[1528179816] transaction","detail":"{read_only:false; response_revision:21506; number_of_response:1; }","duration":"158.952207ms","start":"2026-10-17T12:16:16.422875Z","end":"2026-10-17T12:16:16.581827Z","steps":["trace[1528179816] 'process raft request' (duration: 158.753952ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:16:16.776129Z","caller":"traceutil/trace.go:171","msg":"trace[124251805] linearizableReadLoop","detail":"{readStateIndex:22242; appliedIndex:22241; }","duration":"118.40073ms","start":"2026-10-17T12:16:16.6577Z","end":"2026-10-17T12:16:16.776101Z","steps":["trace[124251805] 'read index received' (duration: 20.628325ms)","trace[124251805] 'applied index is now lower than readState.Index' (duration: 97.771235ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T12:16:16.776352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.640356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-17T12:16:16.77639Z","caller":"traceutil/trace.go:171","msg":"trace[592411630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21507; }","duration":"118.757719ms","start":"2026-10-17T12:16:16.657616Z","end":"2026-10-17T12:16:16.776374Z","steps":["trace[592411630] 'agreement among raft nodes before linearized reading' (duration: 118.582945ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:16:16.776703Z","caller":"traceutil/trace.go:171","msg":"trace[882206296] transaction","detail":"{read_only:false; response_revision:21507; number_of_response:1; }","duration":"199.980989ms","start":"2026-10-17T12:16:16.57668Z","end":"2026-10-17T12:16:16.776661Z","steps":["trace[882206296] 'process raft request' (duration: 101.644068ms)","trace[882206296] 'compare' (duration: 97.646963ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:16:16.973493Z","caller":"traceutil/trace.go:171","msg":"trace[1050336697] transaction","detail":"{read_only:false; response_revision:21508; number_of_response:1; }","duration":"192.585045ms","start":"2026-10-17T12:16:16.78087Z","end":"2026-10-17T12:16:16.973455Z","steps":["trace[1050336697] 'process raft request' (duration: 191.058255ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:16:32.983349Z","caller":"traceutil/trace.go:171","msg":"trace[2073465634] transaction","detail":"{read_only:false; response_revision:21590; number_of_response:1; }","duration":"142.583207ms","start":"2026-10-17T12:16:32.840738Z","end":"2026-10-17T12:16:32.983321Z","steps":["trace[2073465634] 'process raft request' (duration: 142.420103ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:16:33.318603Z","caller":"traceutil/trace.go:171","msg":"trace[1312791506] transaction","detail":"{read_only:false; response_revision:21591; number_of_response:1; }","duration":"139.744222ms","start":"2026-10-17T12:16:33.178846Z","end":"2026-10-17T12:16:33.31859Z","steps":["trace[1312791506] 'process raft request' (duration: 139.587218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:16:33.31869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.199336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T12:16:33.318592Z","caller":"traceutil/trace.go:171","msg":"trace[1189483935] linearizableReadLoop","detail":"{readStateIndex:22330; appliedIndex:22329; }","duration":"134.101824ms","start":"2026-10-17T12:16:33.184463Z","end":"2026-10-17T12:16:33.318565Z","steps":["trace[1189483935] 'read index received' (duration: 133.917229ms)","trace[1189483935] 'applied index is now lower than readState.Index' (duration: 182.865µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:16:33.31873Z","caller":"traceutil/trace.go:171","msg":"trace[509440714] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:21591; }","duration":"134.267328ms","start":"2026-10-17T12:16:33.184452Z","end":"2026-10-17T12:16:33.318719Z","steps":["trace[509440714] 'agreement among raft nodes before linearized reading' (duration: 134.185685ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:16:33.681266Z","caller":"traceutil/trace.go:171","msg":"trace[1345014255] transaction","detail":"{read_only:false; response_revision:21592; number_of_response:1; }","duration":"104.408571ms","start":"2026-10-17T12:16:33.576822Z","end":"2026-10-17T12:16:33.68123Z","steps":["trace[1345014255] 'process raft request' (duration: 104.227056ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:16:34.078586Z","caller":"traceutil/trace.go:171","msg":"trace[233231142] transaction","detail":"{read_only:false; response_revision:21593; number_of_response:1; }","duration":"499.061948ms","start":"2026-10-17T12:16:33.579493Z","end":"2026-10-17T12:16:34.078555Z","steps":["trace[233231142] 'process raft request' (duration: 498.863053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:16:34.078805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:16:33.579463Z","time spent":"499.209332ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T12:16:34.078866Z","caller":"traceutil/trace.go:171","msg":"trace[3144841] linearizableReadLoop","detail":"{readStateIndex:22333; appliedIndex:22331; }","duration":"440.896425ms","start":"2026-10-17T12:16:33.637919Z","end":"2026-10-17T12:16:34.078816Z","steps":["trace[3144841] 'read index received' (duration: 43.860244ms)","trace[3144841] 'applied index is now lower than readState.Index' (duration: 397.033031ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:16:34.078984Z","caller":"traceutil/trace.go:171","msg":"trace[657429140] transaction","detail":"{read_only:false; response_revision:21594; number_of_response:1; }","duration":"495.816574ms","start":"2026-10-17T12:16:33.58313Z","end":"2026-10-17T12:16:34.078947Z","steps":["trace[657429140] 'process raft request' (duration: 495.385482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:16:34.079193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:16:33.583102Z","time spent":"495.977047ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T12:16:34.079241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.321817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-17T12:16:34.079298Z","caller":"traceutil/trace.go:171","msg":"trace[334722750] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21594; }","duration":"441.418199ms","start":"2026-10-17T12:16:33.637863Z","end":"2026-10-17T12:16:34.079281Z","steps":["trace[334722750] 'agreement among raft nodes before linearized reading' (duration: 441.219054ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:16:34.079332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:16:33.637849Z","time spent":"441.4758ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-17T12:16:34.079458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.109478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-17T12:16:34.079531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.966147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-17T12:16:34.079564Z","caller":"traceutil/trace.go:171","msg":"trace[2134945465] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:21594; }","duration":"379.258231ms","start":"2026-10-17T12:16:33.700294Z","end":"2026-10-17T12:16:34.079552Z","steps":["trace[2134945465] 'agreement among raft nodes before linearized reading' (duration: 379.121038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:16:34.079603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:16:33.70027Z","time spent":"379.321453ms","remote":"127.0.0.1:33174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":15,"response size":31,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-10-17T12:16:34.079602Z","caller":"traceutil/trace.go:171","msg":"trace[116186006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21594; }","duration":"433.078369ms","start":"2026-10-17T12:16:33.646507Z","end":"2026-10-17T12:16:34.079585Z","steps":["trace[116186006] 'agreement among raft nodes before linearized reading' (duration: 432.807752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:16:34.079653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:16:33.646497Z","time spent":"433.142941ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-17T12:16:51.808423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19699} {"level":"info","ts":"2026-10-17T12:16:51.950427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19699,"took":"138.123349ms","hash":4274776111,"current-db-size-bytes":56291328,"current-db-size":"56 MB","current-db-size-in-use-bytes":29872128,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T12:16:51.950503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4274776111,"revision":19699,"compact-revision":17057} {"level":"info","ts":"2026-10-17T12:20:27.0951Z","caller":"traceutil/trace.go:171","msg":"trace[535899120] transaction","detail":"{read_only:false; response_revision:23558; number_of_response:1; }","duration":"126.988677ms","start":"2026-10-17T12:20:26.968063Z","end":"2026-10-17T12:20:27.095052Z","steps":["trace[535899120] 'process raft request' (duration: 125.471247ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:20:38.691075Z","caller":"traceutil/trace.go:171","msg":"trace[210056241] linearizableReadLoop","detail":"{readStateIndex:24927; appliedIndex:24926; }","duration":"198.286451ms","start":"2026-10-17T12:20:38.492761Z","end":"2026-10-17T12:20:38.691048Z","steps":["trace[210056241] 'read index received' (duration: 198.26096ms)","trace[210056241] 'applied index is now lower than readState.Index' (duration: 24.491µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:20:38.69119Z","caller":"traceutil/trace.go:171","msg":"trace[1387869711] transaction","detail":"{read_only:false; response_revision:24114; number_of_response:1; }","duration":"316.013706ms","start":"2026-10-17T12:20:38.375165Z","end":"2026-10-17T12:20:38.691179Z","steps":["trace[1387869711] 'process raft request' (duration: 315.721428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:20:38.691292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:20:38.375142Z","time spent":"316.073627ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T12:20:38.691462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.676582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-10-17T12:20:38.69155Z","caller":"traceutil/trace.go:171","msg":"trace[812733762] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24114; }","duration":"198.821965ms","start":"2026-10-17T12:20:38.492707Z","end":"2026-10-17T12:20:38.691529Z","steps":["trace[812733762] 'agreement among raft nodes before linearized reading' (duration: 198.596149ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:20:38.779888Z","caller":"traceutil/trace.go:171","msg":"trace[1751746943] transaction","detail":"{read_only:false; response_revision:24115; number_of_response:1; }","duration":"125.042336ms","start":"2026-10-17T12:20:38.654819Z","end":"2026-10-17T12:20:38.779861Z","steps":["trace[1751746943] 'process raft request' (duration: 61.915527ms)","trace[1751746943] 'compare' (duration: 62.658626ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T12:20:38.78055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.498985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-17T12:20:38.780656Z","caller":"traceutil/trace.go:171","msg":"trace[1445030078] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24116; }","duration":"115.654089ms","start":"2026-10-17T12:20:38.664977Z","end":"2026-10-17T12:20:38.780632Z","steps":["trace[1445030078] 'agreement among raft nodes before linearized reading' (duration: 115.30186ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:21:51.815871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21685} {"level":"info","ts":"2026-10-17T12:21:51.932689Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21685,"took":"111.202492ms","hash":2365464547,"current-db-size-bytes":59113472,"current-db-size":"59 MB","current-db-size-in-use-bytes":43339776,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-17T12:21:51.932858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2365464547,"revision":21685,"compact-revision":19699} {"level":"info","ts":"2026-10-17T12:23:26.046471Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067ef.wal"} {"level":"info","ts":"2026-10-17T12:23:54.717548Z","caller":"traceutil/trace.go:171","msg":"trace[155314767] linearizableReadLoop","detail":"{readStateIndex:26869; appliedIndex:26868; }","duration":"147.867983ms","start":"2026-10-17T12:23:54.569651Z","end":"2026-10-17T12:23:54.717519Z","steps":["trace[155314767] 'read index received' (duration: 97.335529ms)","trace[155314767] 'applied index is now lower than readState.Index' (duration: 50.531014ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:23:54.717579Z","caller":"traceutil/trace.go:171","msg":"trace[598203131] transaction","detail":"{read_only:false; response_revision:26009; number_of_response:1; }","duration":"152.346202ms","start":"2026-10-17T12:23:54.565195Z","end":"2026-10-17T12:23:54.717542Z","steps":["trace[598203131] 'process raft request' (duration: 101.480849ms)","trace[598203131] 'compare' (duration: 50.285428ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T12:23:54.71772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.048108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T12:23:54.717833Z","caller":"traceutil/trace.go:171","msg":"trace[2106824675] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:26009; }","duration":"148.176861ms","start":"2026-10-17T12:23:54.569609Z","end":"2026-10-17T12:23:54.717786Z","steps":["trace[2106824675] 'agreement among raft nodes before linearized reading' (duration: 148.042877ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:23:54.718857Z","caller":"traceutil/trace.go:171","msg":"trace[1043651151] transaction","detail":"{read_only:false; response_revision:26014; number_of_response:1; }","duration":"143.359454ms","start":"2026-10-17T12:23:54.575479Z","end":"2026-10-17T12:23:54.718839Z","steps":["trace[1043651151] 'process raft request' (duration: 143.136689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:23:54.719005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.262989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-17T12:23:54.719061Z","caller":"traceutil/trace.go:171","msg":"trace[986685623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26016; }","duration":"116.363122ms","start":"2026-10-17T12:23:54.602686Z","end":"2026-10-17T12:23:54.719049Z","steps":["trace[986685623] 'agreement among raft nodes before linearized reading' (duration: 116.090125ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:23:54.719081Z","caller":"traceutil/trace.go:171","msg":"trace[787358370] transaction","detail":"{read_only:false; response_revision:26010; number_of_response:1; }","duration":"146.98079ms","start":"2026-10-17T12:23:54.572088Z","end":"2026-10-17T12:23:54.719069Z","steps":["trace[787358370] 'process raft request' (duration: 146.289742ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:23:54.719163Z","caller":"traceutil/trace.go:171","msg":"trace[124595901] transaction","detail":"{read_only:false; response_revision:26015; number_of_response:1; }","duration":"143.031795ms","start":"2026-10-17T12:23:54.576107Z","end":"2026-10-17T12:23:54.719139Z","steps":["trace[124595901] 'process raft request' (duration: 142.542632ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:23:54.719204Z","caller":"traceutil/trace.go:171","msg":"trace[1286809133] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"144.013731ms","start":"2026-10-17T12:23:54.575176Z","end":"2026-10-17T12:23:54.71919Z","steps":["trace[1286809133] 'process raft request' (duration: 143.400186ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:23:54.719199Z","caller":"traceutil/trace.go:171","msg":"trace[1961159391] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"144.145805ms","start":"2026-10-17T12:23:54.575017Z","end":"2026-10-17T12:23:54.719163Z","steps":["trace[1961159391] 'process raft request' (duration: 143.514118ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:23:54.719223Z","caller":"traceutil/trace.go:171","msg":"trace[1310123331] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"144.340251ms","start":"2026-10-17T12:23:54.57486Z","end":"2026-10-17T12:23:54.7192Z","steps":["trace[1310123331] 'process raft request' (duration: 143.620232ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:23:54.719249Z","caller":"traceutil/trace.go:171","msg":"trace[724893941] transaction","detail":"{read_only:false; response_revision:26016; number_of_response:1; }","duration":"130.6655ms","start":"2026-10-17T12:23:54.588571Z","end":"2026-10-17T12:23:54.719237Z","steps":["trace[724893941] 'process raft request' (duration: 130.121096ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:23:54.719657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.974119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-17T12:23:54.719705Z","caller":"traceutil/trace.go:171","msg":"trace[983802955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26016; }","duration":"111.071752ms","start":"2026-10-17T12:23:54.608622Z","end":"2026-10-17T12:23:54.719694Z","steps":["trace[983802955] 'agreement among raft nodes before linearized reading' (duration: 110.646191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:23:54.921262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.934117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-10-17T12:23:54.921359Z","caller":"traceutil/trace.go:171","msg":"trace[728441740] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:26027; }","duration":"117.068901ms","start":"2026-10-17T12:23:54.804266Z","end":"2026-10-17T12:23:54.921335Z","steps":["trace[728441740] 'agreement among raft nodes before linearized reading' (duration: 116.803544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:23:54.922102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.933709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225402"} {"level":"info","ts":"2026-10-17T12:23:54.922183Z","caller":"traceutil/trace.go:171","msg":"trace[1596418774] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:26027; }","duration":"119.054413ms","start":"2026-10-17T12:23:54.80311Z","end":"2026-10-17T12:23:54.922165Z","steps":["trace[1596418774] 'agreement among raft nodes before linearized reading' (duration: 117.991355ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:23:55.527404Z","caller":"traceutil/trace.go:171","msg":"trace[389412114] linearizableReadLoop","detail":"{readStateIndex:26893; appliedIndex:26892; }","duration":"229.643001ms","start":"2026-10-17T12:23:55.297726Z","end":"2026-10-17T12:23:55.527369Z","steps":["trace[389412114] 'read index received' (duration: 206.242184ms)","trace[389412114] 'applied index is now lower than readState.Index' (duration: 23.398927ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T12:23:55.527572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:23:55.204059Z","time spent":"323.50717ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-17T12:23:55.527596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.843237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T12:23:55.527771Z","caller":"traceutil/trace.go:171","msg":"trace[760536785] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:26030; }","duration":"230.089965ms","start":"2026-10-17T12:23:55.297669Z","end":"2026-10-17T12:23:55.527759Z","steps":["trace[760536785] 'agreement among raft nodes before linearized reading' (duration: 229.858849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:23:55.528051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.920033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"info","ts":"2026-10-17T12:23:55.528126Z","caller":"traceutil/trace.go:171","msg":"trace[753418997] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:26030; }","duration":"218.035927ms","start":"2026-10-17T12:23:55.310072Z","end":"2026-10-17T12:23:55.528108Z","steps":["trace[753418997] 'agreement among raft nodes before linearized reading' (duration: 217.808261ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:24:07.880663Z","caller":"traceutil/trace.go:171","msg":"trace[785147793] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"260.150197ms","start":"2026-10-17T12:24:07.620483Z","end":"2026-10-17T12:24:07.880634Z","steps":["trace[785147793] 'process raft request' (duration: 259.892621ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:24:08.002702Z","caller":"traceutil/trace.go:171","msg":"trace[187426424] transaction","detail":"{read_only:false; response_revision:26136; number_of_response:1; }","duration":"291.839344ms","start":"2026-10-17T12:24:07.71083Z","end":"2026-10-17T12:24:08.002669Z","steps":["trace[187426424] 'process raft request' (duration: 290.139009ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:24:08.002711Z","caller":"traceutil/trace.go:171","msg":"trace[375131897] transaction","detail":"{read_only:false; response_revision:26137; number_of_response:1; }","duration":"202.825905ms","start":"2026-10-17T12:24:07.799829Z","end":"2026-10-17T12:24:08.002655Z","steps":["trace[375131897] 'process raft request' (duration: 202.669651ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:24:08.002711Z","caller":"traceutil/trace.go:171","msg":"trace[614516854] transaction","detail":"{read_only:false; response_revision:26138; number_of_response:1; }","duration":"192.070161ms","start":"2026-10-17T12:24:07.810616Z","end":"2026-10-17T12:24:08.002686Z","steps":["trace[614516854] 'process raft request' (duration: 191.964388ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:24:08.356917Z","caller":"traceutil/trace.go:171","msg":"trace[1157292956] linearizableReadLoop","detail":"{readStateIndex:27005; appliedIndex:27004; }","duration":"304.014385ms","start":"2026-10-17T12:24:08.052871Z","end":"2026-10-17T12:24:08.356885Z","steps":["trace[1157292956] 'read index received' (duration: 303.45284ms)","trace[1157292956] 'applied index is now lower than readState.Index' (duration: 560.355µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:24:08.356963Z","caller":"traceutil/trace.go:171","msg":"trace[2062555095] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"349.712223ms","start":"2026-10-17T12:24:08.007212Z","end":"2026-10-17T12:24:08.356924Z","steps":["trace[2062555095] 'process raft request' (duration: 348.972924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:24:08.357077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.186589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-17T12:24:08.357139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:24:08.007153Z","time spent":"349.890447ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T12:24:08.357151Z","caller":"traceutil/trace.go:171","msg":"trace[722932294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26140; }","duration":"304.339804ms","start":"2026-10-17T12:24:08.0528Z","end":"2026-10-17T12:24:08.35714Z","steps":["trace[722932294] 'agreement among raft nodes before linearized reading' (duration: 304.22031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:24:08.357189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:24:08.052771Z","time spent":"304.409166ms","remote":"127.0.0.1:33030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-17T12:24:08.368344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.241948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-10-17T12:24:08.36845Z","caller":"traceutil/trace.go:171","msg":"trace[1389771007] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:26141; }","duration":"177.402103ms","start":"2026-10-17T12:24:08.191019Z","end":"2026-10-17T12:24:08.368421Z","steps":["trace[1389771007] 'agreement among raft nodes before linearized reading' (duration: 177.115626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:24:08.814678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.57773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T12:24:08.81488Z","caller":"traceutil/trace.go:171","msg":"trace[1937704702] linearizableReadLoop","detail":"{readStateIndex:27007; appliedIndex:27006; }","duration":"340.025707ms","start":"2026-10-17T12:24:08.474821Z","end":"2026-10-17T12:24:08.814847Z","steps":["trace[1937704702] 'read index received' (duration: 71.131309ms)","trace[1937704702] 'applied index is now lower than readState.Index' (duration: 268.891648ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:24:08.815054Z","caller":"traceutil/trace.go:171","msg":"trace[1614058077] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"359.930462ms","start":"2026-10-17T12:24:08.455103Z","end":"2026-10-17T12:24:08.815033Z","steps":["trace[1614058077] 'process raft request' (duration: 90.829298ms)","trace[1614058077] 'compare' (duration: 268.297702ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T12:24:08.815153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:24:08.455069Z","time spent":"360.031455ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T12:24:08.815262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.427187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-17T12:24:08.815349Z","caller":"traceutil/trace.go:171","msg":"trace[49618646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26142; }","duration":"340.575621ms","start":"2026-10-17T12:24:08.474753Z","end":"2026-10-17T12:24:08.815328Z","steps":["trace[49618646] 'agreement among raft nodes before linearized reading' (duration: 340.298434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:24:08.815399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:24:08.474727Z","time spent":"340.660363ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-17T12:24:08.815673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.22762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T12:24:08.815838Z","caller":"traceutil/trace.go:171","msg":"trace[685267756] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:26143; }","duration":"274.420194ms","start":"2026-10-17T12:24:08.541362Z","end":"2026-10-17T12:24:08.815782Z","steps":["trace[685267756] 'agreement among raft nodes before linearized reading' (duration: 274.191649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:24:08.81584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.536845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-17T12:24:08.816052Z","caller":"traceutil/trace.go:171","msg":"trace[1082020597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26143; }","duration":"225.828282ms","start":"2026-10-17T12:24:08.590203Z","end":"2026-10-17T12:24:08.816031Z","steps":["trace[1082020597] 'agreement among raft nodes before linearized reading' (duration: 225.462172ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:25:03.453461Z","caller":"traceutil/trace.go:171","msg":"trace[284798424] transaction","detail":"{read_only:false; response_revision:26664; number_of_response:1; }","duration":"260.484316ms","start":"2026-10-17T12:25:03.192956Z","end":"2026-10-17T12:25:03.453441Z","steps":["trace[284798424] 'process raft request' (duration: 220.44823ms)","trace[284798424] 'compare' (duration: 39.646766ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T12:25:03.867554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.359904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T12:25:03.867703Z","caller":"traceutil/trace.go:171","msg":"trace[429523896] transaction","detail":"{read_only:false; response_revision:26665; number_of_response:1; }","duration":"413.205659ms","start":"2026-10-17T12:25:03.45447Z","end":"2026-10-17T12:25:03.867676Z","steps":["trace[429523896] 'process raft request' (duration: 130.587148ms)","trace[429523896] 'compare' (duration: 281.908992ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T12:25:03.867786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:25:03.454443Z","time spent":"413.295872ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T12:25:04.457069Z","caller":"traceutil/trace.go:171","msg":"trace[1304258512] transaction","detail":"{read_only:false; response_revision:26666; number_of_response:1; }","duration":"346.694282ms","start":"2026-10-17T12:25:04.110346Z","end":"2026-10-17T12:25:04.45704Z","steps":["trace[1304258512] 'process raft request' (duration: 346.539978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:25:04.457241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T12:25:04.11031Z","time spent":"346.839026ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T12:25:04.457967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.916209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T12:25:04.457614Z","caller":"traceutil/trace.go:171","msg":"trace[1088472841] linearizableReadLoop","detail":"{readStateIndex:27544; appliedIndex:27544; }","duration":"279.52767ms","start":"2026-10-17T12:25:04.178025Z","end":"2026-10-17T12:25:04.457553Z","steps":["trace[1088472841] 'read index received' (duration: 279.489029ms)","trace[1088472841] 'applied index is now lower than readState.Index' (duration: 34.76µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T12:25:04.458077Z","caller":"traceutil/trace.go:171","msg":"trace[804505586] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26666; }","duration":"280.083284ms","start":"2026-10-17T12:25:04.177969Z","end":"2026-10-17T12:25:04.458052Z","steps":["trace[804505586] 'agreement among raft nodes before linearized reading' (duration: 279.895779ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:25:04.504311Z","caller":"traceutil/trace.go:171","msg":"trace[255576103] transaction","detail":"{read_only:false; response_revision:26668; number_of_response:1; }","duration":"265.839948ms","start":"2026-10-17T12:25:04.238453Z","end":"2026-10-17T12:25:04.504293Z","steps":["trace[255576103] 'process raft request' (duration: 265.756076ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:25:04.504344Z","caller":"traceutil/trace.go:171","msg":"trace[812550989] transaction","detail":"{read_only:false; response_revision:26667; number_of_response:1; }","duration":"296.511208ms","start":"2026-10-17T12:25:04.207799Z","end":"2026-10-17T12:25:04.50431Z","steps":["trace[812550989] 'process raft request' (duration: 296.303933ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:25:04.504715Z","caller":"traceutil/trace.go:171","msg":"trace[184979648] transaction","detail":"{read_only:false; response_revision:26669; number_of_response:1; }","duration":"262.783427ms","start":"2026-10-17T12:25:04.241911Z","end":"2026-10-17T12:25:04.504694Z","steps":["trace[184979648] 'process raft request' (duration: 262.340856ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:25:04.504795Z","caller":"traceutil/trace.go:171","msg":"trace[1973895977] transaction","detail":"{read_only:false; response_revision:26670; number_of_response:1; }","duration":"262.401028ms","start":"2026-10-17T12:25:04.242361Z","end":"2026-10-17T12:25:04.504762Z","steps":["trace[1973895977] 'process raft request' (duration: 262.232234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T12:25:04.504906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.289784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-17T12:25:04.504909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.928196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T12:25:04.504948Z","caller":"traceutil/trace.go:171","msg":"trace[1230752608] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:26670; }","duration":"126.386077ms","start":"2026-10-17T12:25:04.378547Z","end":"2026-10-17T12:25:04.504933Z","steps":["trace[1230752608] 'agreement among raft nodes before linearized reading' (duration: 126.262393ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:25:04.504992Z","caller":"traceutil/trace.go:171","msg":"trace[154501387] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26670; }","duration":"242.104151ms","start":"2026-10-17T12:25:04.262864Z","end":"2026-10-17T12:25:04.504968Z","steps":["trace[154501387] 'agreement among raft nodes before linearized reading' (duration: 241.905085ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T12:26:51.821751Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24930} {"level":"info","ts":"2026-10-17T12:26:51.984487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24930,"took":"158.604371ms","hash":749359975,"current-db-size-bytes":61235200,"current-db-size":"61 MB","current-db-size-in-use-bytes":36179968,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-17T12:26:51.984711Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":749359975,"revision":24930,"compact-revision":21685} {"level":"info","ts":"2026-10-17T12:29:05.458707Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fbe9b088d8de907a","local-member-applied-index":30005,"local-member-snapshot-index":20004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T12:29:05.465265Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30005} {"level":"info","ts":"2026-10-17T12:29:05.465514Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25005} {"level":"info","ts":"2026-10-17T12:31:51.828941Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27708} {"level":"info","ts":"2026-10-17T12:31:51.974551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27708,"took":"139.224122ms","hash":1683454116,"current-db-size-bytes":61235200,"current-db-size":"61 MB","current-db-size-in-use-bytes":33841152,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-17T12:31:51.97462Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1683454116,"revision":27708,"compact-revision":24930} {"level":"info","ts":"2026-10-17T12:36:51.83559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30343} {"level":"info","ts":"2026-10-17T12:36:51.998198Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30343,"took":"159.65756ms","hash":1544083604,"current-db-size-bytes":61235200,"current-db-size":"61 MB","current-db-size-in-use-bytes":30490624,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T12:36:51.998283Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1544083604,"revision":30343,"compact-revision":27708} {"level":"info","ts":"2026-10-17T12:41:51.842418Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32595} {"level":"info","ts":"2026-10-17T12:41:52.011929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32595,"took":"164.881335ms","hash":1019445456,"current-db-size-bytes":62275584,"current-db-size":"62 MB","current-db-size-in-use-bytes":30097408,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T12:41:52.012008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1019445456,"revision":32595,"compact-revision":30343} {"level":"info","ts":"2026-10-17T12:46:51.851162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34824} {"level":"info","ts":"2026-10-17T12:46:51.984501Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34824,"took":"128.49599ms","hash":3643699380,"current-db-size-bytes":62275584,"current-db-size":"62 MB","current-db-size-in-use-bytes":31420416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T12:46:51.9846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3643699380,"revision":34824,"compact-revision":32595} {"level":"info","ts":"2026-10-17T12:50:30.577478Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fbe9b088d8de907a","local-member-applied-index":40006,"local-member-snapshot-index":30005,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T12:50:30.583962Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40006} {"level":"info","ts":"2026-10-17T12:50:30.584144Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35006} {"level":"info","ts":"2026-10-17T12:51:51.859536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37117} {"level":"info","ts":"2026-10-17T12:51:51.996228Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37117,"took":"131.333274ms","hash":337237368,"current-db-size-bytes":62275584,"current-db-size":"62 MB","current-db-size-in-use-bytes":33038336,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-17T12:51:51.996303Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":337237368,"revision":37117,"compact-revision":34824} {"level":"info","ts":"2026-10-17T12:56:51.867792Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39393} {"level":"info","ts":"2026-10-17T12:56:52.01328Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39393,"took":"140.967799ms","hash":4217834532,"current-db-size-bytes":62275584,"current-db-size":"62 MB","current-db-size-in-use-bytes":31039488,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T12:56:52.013393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4217834532,"revision":39393,"compact-revision":37117}