{"level":"warn","ts":"2026-09-01T04:21:38.773816Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T04:21:38.773983Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.10: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.10:2380","--initial-cluster=instance=https://162.253.55.10:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.10:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.10:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-01T04:21:38.774088Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T04:21:38.774098Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-09-01T04:21:38.774124Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-01T04:21:38.775525Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"]} {"level":"info","ts":"2026-09-01T04:21:38.775694Z","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.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.10:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-01T04:21:38.780801Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.698888ms"} {"level":"info","ts":"2026-09-01T04:21:38.788289Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"373d5d49ce4adaff","cluster-id":"467836e907a15d60"} {"level":"info","ts":"2026-09-01T04:21:38.788417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T04:21:38.788454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 0"} {"level":"info","ts":"2026-09-01T04:21:38.78847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 373d5d49ce4adaff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T04:21:38.788481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 1"} {"level":"info","ts":"2026-09-01T04:21:38.788545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"warn","ts":"2026-09-01T04:21:38.794469Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T04:21:38.796245Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T04:21:38.797969Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-01T04:21:38.801148Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"373d5d49ce4adaff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T04:21:38.801575Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T04:21:38.801747Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T04:21:38.801671Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"373d5d49ce4adaff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T04:21:38.801765Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T04:21:38.802581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"info","ts":"2026-09-01T04:21:38.8033Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","added-peer-id":"373d5d49ce4adaff","added-peer-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-09-01T04:21:38.807194Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-01T04:21:38.807541Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"373d5d49ce4adaff","initial-advertise-peer-urls":["https://162.253.55.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T04:21:38.807603Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T04:21:38.808545Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-09-01T04:21:38.808588Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-09-01T04:21:39.18978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T04:21:39.189859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T04:21:39.189885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgPreVoteResp from 373d5d49ce4adaff at term 1"} {"level":"info","ts":"2026-09-01T04:21:39.189902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became candidate at term 2"} {"level":"info","ts":"2026-09-01T04:21:39.189909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgVoteResp from 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-09-01T04:21:39.189925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became leader at term 2"} {"level":"info","ts":"2026-09-01T04:21:39.189936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 373d5d49ce4adaff elected leader 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-09-01T04:21:39.229138Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:21:39.260954Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"373d5d49ce4adaff","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.10:2379]}","request-path":"/0/members/373d5d49ce4adaff/attributes","cluster-id":"467836e907a15d60","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T04:21:39.260967Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T04:21:39.261022Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T04:21:39.261404Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T04:21:39.261444Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T04:21:39.26372Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:21:39.263873Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:21:39.263931Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:21:39.264893Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.10:2379"} {"level":"info","ts":"2026-09-01T04:21:39.264926Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-01T04:22:02.719778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.969316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:22:02.719921Z","caller":"traceutil/trace.go:171","msg":"trace[488126667] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"393.141128ms","start":"2026-09-01T04:22:02.326761Z","end":"2026-09-01T04:22:02.719902Z","steps":["trace[488126667] 'process raft request' (duration: 257.557337ms)","trace[488126667] 'compare' (duration: 134.817842ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:22:02.719988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:02.326731Z","time spent":"393.233939ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T04:22:03.30838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.807764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:22:03.308609Z","caller":"traceutil/trace.go:171","msg":"trace[1968383253] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"584.726138ms","start":"2026-09-01T04:22:02.723838Z","end":"2026-09-01T04:22:03.308564Z","steps":["trace[1968383253] 'process raft request' (duration: 134.655918ms)","trace[1968383253] 'compare' (duration: 449.646061ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:22:03.308678Z","caller":"traceutil/trace.go:171","msg":"trace[97524506] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:440; }","duration":"548.618718ms","start":"2026-09-01T04:22:02.760038Z","end":"2026-09-01T04:22:03.308657Z","steps":["trace[97524506] 'read index received' (duration: 98.267212ms)","trace[97524506] 'applied index is now lower than readState.Index' (duration: 450.350676ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:22:03.308807Z","caller":"traceutil/trace.go:171","msg":"trace[514742631] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"582.081487ms","start":"2026-09-01T04:22:02.726707Z","end":"2026-09-01T04:22:03.308789Z","steps":["trace[514742631] 'process raft request' (duration: 581.872442ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:03.308803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:02.723807Z","time spent":"584.909152ms","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-01T04:22:03.308891Z","caller":"traceutil/trace.go:171","msg":"trace[2060754087] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"584.349269ms","start":"2026-09-01T04:22:02.7245Z","end":"2026-09-01T04:22:03.308849Z","steps":["trace[2060754087] 'process raft request' (duration: 583.993001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:03.309193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:02.726678Z","time spent":"582.182768ms","remote":"127.0.0.1:48306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:22:03.310376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.183971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.10\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-09-01T04:22:03.310517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.363606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:22:03.310559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:02.724464Z","time spent":"584.619676ms","remote":"127.0.0.1:47932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-01T04:22:03.310709Z","caller":"traceutil/trace.go:171","msg":"trace[1969310432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"363.168958ms","start":"2026-09-01T04:22:02.947486Z","end":"2026-09-01T04:22:03.310655Z","steps":["trace[1969310432] 'agreement among raft nodes before linearized reading' (duration: 361.352536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:03.310824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:02.947453Z","time spent":"363.339882ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T04:22:03.310564Z","caller":"traceutil/trace.go:171","msg":"trace[2069428837] range","detail":"{range_begin:/registry/masterleases/162.253.55.10; range_end:; response_count:1; response_revision:431; }","duration":"550.505901ms","start":"2026-09-01T04:22:02.760031Z","end":"2026-09-01T04:22:03.310537Z","steps":["trace[2069428837] 'agreement among raft nodes before linearized reading' (duration: 549.14296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:03.311212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:02.75996Z","time spent":"551.219646ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.10\" "} {"level":"info","ts":"2026-09-01T04:22:23.031957Z","caller":"traceutil/trace.go:171","msg":"trace[1790117147] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"158.784089ms","start":"2026-09-01T04:22:22.87315Z","end":"2026-09-01T04:22:23.031934Z","steps":["trace[1790117147] 'read index received' (duration: 157.993691ms)","trace[1790117147] 'applied index is now lower than readState.Index' (duration: 789.558µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:22:23.032032Z","caller":"traceutil/trace.go:171","msg":"trace[209599326] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"230.574371ms","start":"2026-09-01T04:22:22.801432Z","end":"2026-09-01T04:22:23.032007Z","steps":["trace[209599326] 'process raft request' (duration: 229.964307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:23.032222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.033225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5hcxq\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-01T04:22:23.032364Z","caller":"traceutil/trace.go:171","msg":"trace[1924853554] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5hcxq; range_end:; response_count:1; response_revision:627; }","duration":"159.233669ms","start":"2026-09-01T04:22:22.873096Z","end":"2026-09-01T04:22:23.03233Z","steps":["trace[1924853554] 'agreement among raft nodes before linearized reading' (duration: 158.949593ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:23.310796Z","caller":"traceutil/trace.go:171","msg":"trace[103059213] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"193.989905ms","start":"2026-09-01T04:22:23.116761Z","end":"2026-09-01T04:22:23.310751Z","steps":["trace[103059213] 'process raft request' (duration: 193.934343ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:23.310861Z","caller":"traceutil/trace.go:171","msg":"trace[227512111] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"247.700418ms","start":"2026-09-01T04:22:23.063117Z","end":"2026-09-01T04:22:23.310818Z","steps":["trace[227512111] 'process raft request' (duration: 183.356924ms)","trace[227512111] 'compare' (duration: 64.038077ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:22:24.256235Z","caller":"traceutil/trace.go:171","msg":"trace[428322878] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"308.056932ms","start":"2026-09-01T04:22:23.94815Z","end":"2026-09-01T04:22:24.256207Z","steps":["trace[428322878] 'read index received' (duration: 298.261201ms)","trace[428322878] 'applied index is now lower than readState.Index' (duration: 9.792911ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:22:24.25627Z","caller":"traceutil/trace.go:171","msg":"trace[1064475247] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"532.705309ms","start":"2026-09-01T04:22:23.723532Z","end":"2026-09-01T04:22:24.256237Z","steps":["trace[1064475247] 'process raft request' (duration: 523.188953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:24.256376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.217245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:22:24.25642Z","caller":"traceutil/trace.go:171","msg":"trace[1010088602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"308.303677ms","start":"2026-09-01T04:22:23.948102Z","end":"2026-09-01T04:22:24.256405Z","steps":["trace[1010088602] 'agreement among raft nodes before linearized reading' (duration: 308.221586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:24.256423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:23.723501Z","time spent":"532.833802ms","remote":"127.0.0.1:47956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:22:24.25648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:23.948077Z","time spent":"308.3928ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T04:22:39.91348Z","caller":"traceutil/trace.go:171","msg":"trace[173357739] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"199.231722ms","start":"2026-09-01T04:22:39.714204Z","end":"2026-09-01T04:22:39.913435Z","steps":["trace[173357739] 'process raft request' (duration: 179.547207ms)","trace[173357739] 'compare' (duration: 19.406279ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:22:39.913522Z","caller":"traceutil/trace.go:171","msg":"trace[667456874] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:817; }","duration":"185.918012ms","start":"2026-09-01T04:22:39.727579Z","end":"2026-09-01T04:22:39.913497Z","steps":["trace[667456874] 'read index received' (duration: 166.101064ms)","trace[667456874] 'applied index is now lower than readState.Index' (duration: 19.815798ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:22:39.913634Z","caller":"traceutil/trace.go:171","msg":"trace[1821549015] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"199.323764ms","start":"2026-09-01T04:22:39.714231Z","end":"2026-09-01T04:22:39.913555Z","steps":["trace[1821549015] 'process raft request' (duration: 199.176581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:39.913896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.803523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-01T04:22:39.913994Z","caller":"traceutil/trace.go:171","msg":"trace[754271708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"171.945295ms","start":"2026-09-01T04:22:39.742025Z","end":"2026-09-01T04:22:39.913971Z","steps":["trace[754271708] 'agreement among raft nodes before linearized reading' (duration: 171.69865ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:39.914044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.253559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-01T04:22:39.914241Z","caller":"traceutil/trace.go:171","msg":"trace[735204815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"186.682888ms","start":"2026-09-01T04:22:39.72754Z","end":"2026-09-01T04:22:39.914223Z","steps":["trace[735204815] 'agreement among raft nodes before linearized reading' (duration: 186.147376ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:40.137523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.884184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:22:40.137649Z","caller":"traceutil/trace.go:171","msg":"trace[1258842445] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"219.712955ms","start":"2026-09-01T04:22:39.917919Z","end":"2026-09-01T04:22:40.137632Z","steps":["trace[1258842445] 'process raft request' (duration: 101.638887ms)","trace[1258842445] 'compare' (duration: 117.777472ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:22:40.138797Z","caller":"traceutil/trace.go:171","msg":"trace[1795952080] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:819; }","duration":"191.15646ms","start":"2026-09-01T04:22:39.947618Z","end":"2026-09-01T04:22:40.138775Z","steps":["trace[1795952080] 'read index received' (duration: 71.949817ms)","trace[1795952080] 'applied index is now lower than readState.Index' (duration: 119.205663ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:22:40.138949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.310464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:22:40.138996Z","caller":"traceutil/trace.go:171","msg":"trace[1736347615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"191.437267ms","start":"2026-09-01T04:22:39.947547Z","end":"2026-09-01T04:22:40.138984Z","steps":["trace[1736347615] 'agreement among raft nodes before linearized reading' (duration: 191.351725ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:40.139027Z","caller":"traceutil/trace.go:171","msg":"trace[42348448] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"220.020912ms","start":"2026-09-01T04:22:39.918872Z","end":"2026-09-01T04:22:40.138893Z","steps":["trace[42348448] 'process raft request' (duration: 219.800087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:40.13913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.608403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-29lfv\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-09-01T04:22:40.139222Z","caller":"traceutil/trace.go:171","msg":"trace[87052561] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-29lfv; range_end:; response_count:1; response_revision:794; }","duration":"154.731417ms","start":"2026-09-01T04:22:39.984468Z","end":"2026-09-01T04:22:40.139199Z","steps":["trace[87052561] 'agreement among raft nodes before linearized reading' (duration: 154.487361ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:23:27.229057Z","caller":"traceutil/trace.go:171","msg":"trace[979803999] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"137.339552ms","start":"2026-09-01T04:23:27.091686Z","end":"2026-09-01T04:23:27.229026Z","steps":["trace[979803999] 'process raft request' (duration: 137.064686ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:23:48.386816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.379106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:23:48.387005Z","caller":"traceutil/trace.go:171","msg":"trace[664342570] linearizableReadLoop","detail":"{readStateIndex:1775; appliedIndex:1773; }","duration":"108.314028ms","start":"2026-09-01T04:23:48.278672Z","end":"2026-09-01T04:23:48.386986Z","steps":["trace[664342570] 'read index received' (duration: 28.33µs)","trace[664342570] 'applied index is now lower than readState.Index' (duration: 108.284668ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:23:48.387066Z","caller":"traceutil/trace.go:171","msg":"trace[2128943328] transaction","detail":"{read_only:false; response_revision:1711; number_of_response:1; }","duration":"251.260432ms","start":"2026-09-01T04:23:48.135776Z","end":"2026-09-01T04:23:48.387036Z","steps":["trace[2128943328] 'process raft request' (duration: 251.154639ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:23:48.387086Z","caller":"traceutil/trace.go:171","msg":"trace[2089215806] transaction","detail":"{read_only:false; response_revision:1710; number_of_response:1; }","duration":"251.318183ms","start":"2026-09-01T04:23:48.135726Z","end":"2026-09-01T04:23:48.387044Z","steps":["trace[2089215806] 'process raft request' (duration: 94.63141ms)","trace[2089215806] 'compare' (duration: 156.130729ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:23:48.387243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.555424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-01T04:23:48.387299Z","caller":"traceutil/trace.go:171","msg":"trace[1759646937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1711; }","duration":"108.654136ms","start":"2026-09-01T04:23:48.278631Z","end":"2026-09-01T04:23:48.387285Z","steps":["trace[1759646937] 'agreement among raft nodes before linearized reading' (duration: 108.451432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:23:48.389342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.80196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-01T04:23:48.389407Z","caller":"traceutil/trace.go:171","msg":"trace[716622710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1712; }","duration":"102.900562ms","start":"2026-09-01T04:23:48.286492Z","end":"2026-09-01T04:23:48.389393Z","steps":["trace[716622710] 'agreement among raft nodes before linearized reading' (duration: 102.715588ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:02.294402Z","caller":"traceutil/trace.go:171","msg":"trace[1007589474] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1965; }","duration":"347.09295ms","start":"2026-09-01T04:24:01.947278Z","end":"2026-09-01T04:24:02.294371Z","steps":["trace[1007589474] 'read index received' (duration: 346.804682ms)","trace[1007589474] 'applied index is now lower than readState.Index' (duration: 287.258µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:24:02.294557Z","caller":"traceutil/trace.go:171","msg":"trace[1092552496] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"424.580782ms","start":"2026-09-01T04:24:01.869932Z","end":"2026-09-01T04:24:02.294513Z","steps":["trace[1092552496] 'process raft request' (duration: 424.171432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:02.29476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:01.86991Z","time spent":"424.754455ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:24:02.294766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.364146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:24:02.294774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.887056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-01T04:24:02.294883Z","caller":"traceutil/trace.go:171","msg":"trace[362948663] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1896; }","duration":"101.037679ms","start":"2026-09-01T04:24:02.193833Z","end":"2026-09-01T04:24:02.294871Z","steps":["trace[362948663] 'agreement among raft nodes before linearized reading' (duration: 100.781653ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:02.29489Z","caller":"traceutil/trace.go:171","msg":"trace[386326969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"347.582661ms","start":"2026-09-01T04:24:01.947226Z","end":"2026-09-01T04:24:02.294809Z","steps":["trace[386326969] 'agreement among raft nodes before linearized reading' (duration: 347.361406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:02.294986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:01.947204Z","time spent":"347.766564ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T04:24:02.481749Z","caller":"traceutil/trace.go:171","msg":"trace[1983195451] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1966; }","duration":"117.134153ms","start":"2026-09-01T04:24:02.364563Z","end":"2026-09-01T04:24:02.481697Z","steps":["trace[1983195451] 'read index received' (duration: 116.870837ms)","trace[1983195451] 'applied index is now lower than readState.Index' (duration: 262.396µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:24:02.481791Z","caller":"traceutil/trace.go:171","msg":"trace[2033690387] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"182.097273ms","start":"2026-09-01T04:24:02.299666Z","end":"2026-09-01T04:24:02.481764Z","steps":["trace[2033690387] 'process raft request' (duration: 181.843948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:02.481994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.42779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-01T04:24:02.482143Z","caller":"traceutil/trace.go:171","msg":"trace[1116299450] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1897; }","duration":"117.643355ms","start":"2026-09-01T04:24:02.364481Z","end":"2026-09-01T04:24:02.482124Z","steps":["trace[1116299450] 'agreement among raft nodes before linearized reading' (duration: 117.382058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:02.855339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.635777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:24:02.855456Z","caller":"traceutil/trace.go:171","msg":"trace[892702735] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1967; }","duration":"361.900253ms","start":"2026-09-01T04:24:02.493538Z","end":"2026-09-01T04:24:02.855438Z","steps":["trace[892702735] 'read index received' (duration: 27.290863ms)","trace[892702735] 'applied index is now lower than readState.Index' (duration: 334.60753ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:24:02.85559Z","caller":"traceutil/trace.go:171","msg":"trace[374154583] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"364.892792ms","start":"2026-09-01T04:24:02.490659Z","end":"2026-09-01T04:24:02.855552Z","steps":["trace[374154583] 'process raft request' (duration: 193.946658ms)","trace[374154583] 'compare' (duration: 170.456203ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:24:02.855671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:02.490631Z","time spent":"364.995055ms","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:24:02.855694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.666409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-92f23301-2e36-4d65-b315-c5db63ffc926\" ","response":"range_response_count:1 size:3743"} {"level":"warn","ts":"2026-09-01T04:24:02.855749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.164428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-01T04:24:02.855756Z","caller":"traceutil/trace.go:171","msg":"trace[2023244592] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-92f23301-2e36-4d65-b315-c5db63ffc926; range_end:; response_count:1; response_revision:1899; }","duration":"186.774622ms","start":"2026-09-01T04:24:02.668963Z","end":"2026-09-01T04:24:02.855737Z","steps":["trace[2023244592] 'agreement among raft nodes before linearized reading' (duration: 186.663869ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:02.855799Z","caller":"traceutil/trace.go:171","msg":"trace[809270617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1899; }","duration":"192.25503ms","start":"2026-09-01T04:24:02.66353Z","end":"2026-09-01T04:24:02.855785Z","steps":["trace[809270617] 'agreement among raft nodes before linearized reading' (duration: 192.063205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:02.855794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.715503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-01T04:24:02.855863Z","caller":"traceutil/trace.go:171","msg":"trace[806615813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1899; }","duration":"128.856956ms","start":"2026-09-01T04:24:02.726989Z","end":"2026-09-01T04:24:02.855846Z","steps":["trace[806615813] 'agreement among raft nodes before linearized reading' (duration: 128.6326ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:02.856043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.523078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-01T04:24:02.856068Z","caller":"traceutil/trace.go:171","msg":"trace[1924637561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1899; }","duration":"362.59982ms","start":"2026-09-01T04:24:02.49346Z","end":"2026-09-01T04:24:02.85606Z","steps":["trace[1924637561] 'agreement among raft nodes before linearized reading' (duration: 362.518758ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:02.856093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:02.493437Z","time spent":"362.648381ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-01T04:24:15.220379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.464821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:24:15.220502Z","caller":"traceutil/trace.go:171","msg":"trace[591607224] linearizableReadLoop","detail":"{readStateIndex:2078; appliedIndex:2077; }","duration":"171.798104ms","start":"2026-09-01T04:24:15.048682Z","end":"2026-09-01T04:24:15.22048Z","steps":["trace[591607224] 'read index received' (duration: 46.591µs)","trace[591607224] 'applied index is now lower than readState.Index' (duration: 171.749803ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:24:15.220522Z","caller":"traceutil/trace.go:171","msg":"trace[7075806] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"212.457312ms","start":"2026-09-01T04:24:15.008034Z","end":"2026-09-01T04:24:15.220492Z","steps":["trace[7075806] 'process raft request' (duration: 22.793926ms)","trace[7075806] 'compare' (duration: 189.344898ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:24:15.220599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.900726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:24:15.22064Z","caller":"traceutil/trace.go:171","msg":"trace[2016450582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2004; }","duration":"172.009238ms","start":"2026-09-01T04:24:15.048617Z","end":"2026-09-01T04:24:15.220626Z","steps":["trace[2016450582] 'agreement among raft nodes before linearized reading' (duration: 171.918826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:28.155953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.696835ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-09-01T04:24:46.266708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.917321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:24:46.266807Z","caller":"traceutil/trace.go:171","msg":"trace[311807469] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"163.932922ms","start":"2026-09-01T04:24:46.102862Z","end":"2026-09-01T04:24:46.266795Z","steps":["trace[311807469] 'process raft request' (duration: 35.860188ms)","trace[311807469] 'compare' (duration: 127.621684ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:24:52.22201Z","caller":"traceutil/trace.go:171","msg":"trace[725895860] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"159.190194ms","start":"2026-09-01T04:24:52.062774Z","end":"2026-09-01T04:24:52.221964Z","steps":["trace[725895860] 'process raft request' (duration: 158.934337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:52.614949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.788776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:24:52.615424Z","caller":"traceutil/trace.go:171","msg":"trace[1954215285] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"324.62878ms","start":"2026-09-01T04:24:52.290768Z","end":"2026-09-01T04:24:52.615397Z","steps":["trace[1954215285] 'process raft request' (duration: 151.308801ms)","trace[1954215285] 'compare' (duration: 172.433529ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:24:52.615533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:52.29073Z","time spent":"324.750002ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:25:45.736818Z","caller":"traceutil/trace.go:171","msg":"trace[599345984] linearizableReadLoop","detail":"{readStateIndex:2777; appliedIndex:2776; }","duration":"260.784839ms","start":"2026-09-01T04:25:45.476012Z","end":"2026-09-01T04:25:45.736797Z","steps":["trace[599345984] 'read index received' (duration: 260.579024ms)","trace[599345984] 'applied index is now lower than readState.Index' (duration: 204.975µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:25:45.736911Z","caller":"traceutil/trace.go:171","msg":"trace[1401669522] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"265.218551ms","start":"2026-09-01T04:25:45.471666Z","end":"2026-09-01T04:25:45.736885Z","steps":["trace[1401669522] 'process raft request' (duration: 264.977456ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:25:45.737014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.992853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-01T04:25:45.737064Z","caller":"traceutil/trace.go:171","msg":"trace[274903904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2677; }","duration":"261.080465ms","start":"2026-09-01T04:25:45.47596Z","end":"2026-09-01T04:25:45.73704Z","steps":["trace[274903904] 'agreement among raft nodes before linearized reading' (duration: 260.945392ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:25:45.799035Z","caller":"traceutil/trace.go:171","msg":"trace[732404683] transaction","detail":"{read_only:false; response_revision:2678; number_of_response:1; }","duration":"206.717613ms","start":"2026-09-01T04:25:45.592283Z","end":"2026-09-01T04:25:45.799001Z","steps":["trace[732404683] 'process raft request' (duration: 206.561529ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:25:45.79909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.319081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:25:45.799107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.107558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-01T04:25:45.79914Z","caller":"traceutil/trace.go:171","msg":"trace[771551946] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2678; }","duration":"268.419595ms","start":"2026-09-01T04:25:45.530708Z","end":"2026-09-01T04:25:45.799127Z","steps":["trace[771551946] 'agreement among raft nodes before linearized reading' (duration: 268.315452ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:25:45.799176Z","caller":"traceutil/trace.go:171","msg":"trace[185309553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2678; }","duration":"321.21605ms","start":"2026-09-01T04:25:45.477944Z","end":"2026-09-01T04:25:45.79916Z","steps":["trace[185309553] 'agreement among raft nodes before linearized reading' (duration: 321.024456ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:25:45.799228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:25:45.477931Z","time spent":"321.286662ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-01T04:29:36.096238Z","caller":"traceutil/trace.go:171","msg":"trace[161764788] transaction","detail":"{read_only:false; response_revision:4120; number_of_response:1; }","duration":"302.414137ms","start":"2026-09-01T04:29:35.793803Z","end":"2026-09-01T04:29:36.096217Z","steps":["trace[161764788] 'process raft request' (duration: 279.885938ms)","trace[161764788] 'compare' (duration: 22.336564ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:29:36.096215Z","caller":"traceutil/trace.go:171","msg":"trace[1421009402] linearizableReadLoop","detail":"{readStateIndex:4274; appliedIndex:4273; }","duration":"282.134279ms","start":"2026-09-01T04:29:35.814052Z","end":"2026-09-01T04:29:36.096186Z","steps":["trace[1421009402] 'read index received' (duration: 259.578699ms)","trace[1421009402] 'applied index is now lower than readState.Index' (duration: 22.55445ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:29:36.096404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:29:35.793776Z","time spent":"302.524289ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:29:36.096745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.679051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n8nrc\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-01T04:29:36.096804Z","caller":"traceutil/trace.go:171","msg":"trace[1970381884] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n8nrc; range_end:; response_count:1; response_revision:4120; }","duration":"282.740442ms","start":"2026-09-01T04:29:35.814047Z","end":"2026-09-01T04:29:36.096787Z","steps":["trace[1970381884] 'agreement among raft nodes before linearized reading' (duration: 282.244771ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:36.096785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.533679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-01T04:29:36.09688Z","caller":"traceutil/trace.go:171","msg":"trace[166268184] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4120; }","duration":"111.674873ms","start":"2026-09-01T04:29:35.985183Z","end":"2026-09-01T04:29:36.096858Z","steps":["trace[166268184] 'agreement among raft nodes before linearized reading' (duration: 111.316575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:31:04.891151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.724315ms","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-09-01T04:31:04.891175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.075602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T04:31:04.891244Z","caller":"traceutil/trace.go:171","msg":"trace[1702659941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5189; }","duration":"111.855267ms","start":"2026-09-01T04:31:04.77937Z","end":"2026-09-01T04:31:04.891225Z","steps":["trace[1702659941] 'range keys from in-memory index tree' (duration: 111.56914ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:04.891282Z","caller":"traceutil/trace.go:171","msg":"trace[1207276628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5189; }","duration":"106.225945ms","start":"2026-09-01T04:31:04.785015Z","end":"2026-09-01T04:31:04.891241Z","steps":["trace[1207276628] 'range keys from in-memory index tree' (duration: 105.895288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:31:04.893113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.095967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210289"} {"level":"info","ts":"2026-09-01T04:31:04.8932Z","caller":"traceutil/trace.go:171","msg":"trace[2112726386] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5189; }","duration":"110.23579ms","start":"2026-09-01T04:31:04.782946Z","end":"2026-09-01T04:31:04.893181Z","steps":["trace[2112726386] 'range keys from in-memory index tree' (duration: 107.897895ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:05.113046Z","caller":"traceutil/trace.go:171","msg":"trace[1043032646] linearizableReadLoop","detail":"{readStateIndex:5390; appliedIndex:5389; }","duration":"122.037877ms","start":"2026-09-01T04:31:04.990987Z","end":"2026-09-01T04:31:05.113024Z","steps":["trace[1043032646] 'read index received' (duration: 100.462431ms)","trace[1043032646] 'applied index is now lower than readState.Index' (duration: 21.574806ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:31:05.113191Z","caller":"traceutil/trace.go:171","msg":"trace[1814481491] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"131.502727ms","start":"2026-09-01T04:31:04.981605Z","end":"2026-09-01T04:31:05.113107Z","steps":["trace[1814481491] 'process raft request' (duration: 109.906202ms)","trace[1814481491] 'compare' (duration: 21.411941ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:31:05.113344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.320083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-01T04:31:05.113447Z","caller":"traceutil/trace.go:171","msg":"trace[1977297192] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5193; }","duration":"122.480187ms","start":"2026-09-01T04:31:04.99094Z","end":"2026-09-01T04:31:05.11342Z","steps":["trace[1977297192] 'agreement among raft nodes before linearized reading' (duration: 122.17451ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:05.353147Z","caller":"traceutil/trace.go:171","msg":"trace[1663574360] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"191.747207ms","start":"2026-09-01T04:31:05.161378Z","end":"2026-09-01T04:31:05.353125Z","steps":["trace[1663574360] 'process raft request' (duration: 191.594804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:31:07.813077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.31263ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:31:07.813224Z","caller":"traceutil/trace.go:171","msg":"trace[1751210322] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5308; }","duration":"101.477464ms","start":"2026-09-01T04:31:07.711723Z","end":"2026-09-01T04:31:07.8132Z","steps":["trace[1751210322] 'range keys from in-memory index tree' (duration: 101.29369ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:07.813457Z","caller":"traceutil/trace.go:171","msg":"trace[2139381552] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"238.192635ms","start":"2026-09-01T04:31:07.575234Z","end":"2026-09-01T04:31:07.813427Z","steps":["trace[2139381552] 'process raft request' (duration: 179.727157ms)","trace[2139381552] 'compare' (duration: 58.355865ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:31:07.817359Z","caller":"traceutil/trace.go:171","msg":"trace[434890515] linearizableReadLoop","detail":"{readStateIndex:5509; appliedIndex:5507; }","duration":"208.663803ms","start":"2026-09-01T04:31:07.608675Z","end":"2026-09-01T04:31:07.817338Z","steps":["trace[434890515] 'read index received' (duration: 146.400716ms)","trace[434890515] 'applied index is now lower than readState.Index' (duration: 62.259767ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:31:07.817373Z","caller":"traceutil/trace.go:171","msg":"trace[2079486903] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"240.842976ms","start":"2026-09-01T04:31:07.576506Z","end":"2026-09-01T04:31:07.817349Z","steps":["trace[2079486903] 'process raft request' (duration: 240.642522ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:07.817614Z","caller":"traceutil/trace.go:171","msg":"trace[1729143224] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"141.42575ms","start":"2026-09-01T04:31:07.676172Z","end":"2026-09-01T04:31:07.817598Z","steps":["trace[1729143224] 'process raft request' (duration: 141.099931ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:31:07.817633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.952901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f6a4d918-7033-45e7-9cde-921386808885\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-01T04:31:07.817856Z","caller":"traceutil/trace.go:171","msg":"trace[1413048350] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f6a4d918-7033-45e7-9cde-921386808885; range_end:; response_count:1; response_revision:5311; }","duration":"209.210657ms","start":"2026-09-01T04:31:07.608626Z","end":"2026-09-01T04:31:07.817837Z","steps":["trace[1413048350] 'agreement among raft nodes before linearized reading' (duration: 208.828778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:31:07.817985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.690152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728676"} {"level":"info","ts":"2026-09-01T04:31:07.818074Z","caller":"traceutil/trace.go:171","msg":"trace[773108908] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5311; }","duration":"134.825425ms","start":"2026-09-01T04:31:07.683234Z","end":"2026-09-01T04:31:07.818059Z","steps":["trace[773108908] 'agreement among raft nodes before linearized reading' (duration: 134.319644ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:09.97612Z","caller":"traceutil/trace.go:171","msg":"trace[42448029] linearizableReadLoop","detail":"{readStateIndex:5544; appliedIndex:5543; }","duration":"154.813223ms","start":"2026-09-01T04:31:09.821284Z","end":"2026-09-01T04:31:09.976097Z","steps":["trace[42448029] 'read index received' (duration: 154.532067ms)","trace[42448029] 'applied index is now lower than readState.Index' (duration: 280.206µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:31:09.976212Z","caller":"traceutil/trace.go:171","msg":"trace[1902885708] transaction","detail":"{read_only:false; response_revision:5342; number_of_response:1; }","duration":"155.596092ms","start":"2026-09-01T04:31:09.82058Z","end":"2026-09-01T04:31:09.976176Z","steps":["trace[1902885708] 'process raft request' (duration: 155.226423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:31:09.97631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.007627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-01T04:31:09.976356Z","caller":"traceutil/trace.go:171","msg":"trace[776558069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5342; }","duration":"155.082998ms","start":"2026-09-01T04:31:09.821257Z","end":"2026-09-01T04:31:09.97634Z","steps":["trace[776558069] 'agreement among raft nodes before linearized reading' (duration: 154.948515ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:10.162434Z","caller":"traceutil/trace.go:171","msg":"trace[1467734647] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"180.771901ms","start":"2026-09-01T04:31:09.981619Z","end":"2026-09-01T04:31:10.162391Z","steps":["trace[1467734647] 'process raft request' (duration: 180.72294ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:10.162462Z","caller":"traceutil/trace.go:171","msg":"trace[187964363] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"340.054429ms","start":"2026-09-01T04:31:09.822365Z","end":"2026-09-01T04:31:10.162419Z","steps":["trace[187964363] 'process raft request' (duration: 303.82183ms)","trace[187964363] 'compare' (duration: 35.985723ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:31:10.16257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.045052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:31:10.162444Z","caller":"traceutil/trace.go:171","msg":"trace[1310971456] linearizableReadLoop","detail":"{readStateIndex:5545; appliedIndex:5544; }","duration":"186.234848ms","start":"2026-09-01T04:31:09.97618Z","end":"2026-09-01T04:31:10.162415Z","steps":["trace[1310971456] 'read index received' (duration: 149.98175ms)","trace[1310971456] 'applied index is now lower than readState.Index' (duration: 36.250588ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:31:10.16261Z","caller":"traceutil/trace.go:171","msg":"trace[1323474139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5344; }","duration":"215.140115ms","start":"2026-09-01T04:31:09.947461Z","end":"2026-09-01T04:31:10.162601Z","steps":["trace[1323474139] 'agreement among raft nodes before linearized reading' (duration: 215.034902ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:31:10.162674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:31:09.822356Z","time spent":"340.208522ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:31:24.085635Z","caller":"traceutil/trace.go:171","msg":"trace[1310973116] linearizableReadLoop","detail":"{readStateIndex:5800; appliedIndex:5799; }","duration":"136.770679ms","start":"2026-09-01T04:31:23.94884Z","end":"2026-09-01T04:31:24.085611Z","steps":["trace[1310973116] 'read index received' (duration: 134.866785ms)","trace[1310973116] 'applied index is now lower than readState.Index' (duration: 1.902774ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:31:24.085764Z","caller":"traceutil/trace.go:171","msg":"trace[609493740] transaction","detail":"{read_only:false; response_revision:5590; number_of_response:1; }","duration":"262.130926ms","start":"2026-09-01T04:31:23.823622Z","end":"2026-09-01T04:31:24.085753Z","steps":["trace[609493740] 'process raft request' (duration: 260.16019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:31:24.085948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.098967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:31:24.086032Z","caller":"traceutil/trace.go:171","msg":"trace[1984131742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5590; }","duration":"137.22116ms","start":"2026-09-01T04:31:23.948792Z","end":"2026-09-01T04:31:24.086013Z","steps":["trace[1984131742] 'agreement among raft nodes before linearized reading' (duration: 137.095697ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:31:24.08649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.870265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-g8clq\" ","response":"range_response_count:1 size:7665"} {"level":"info","ts":"2026-09-01T04:31:24.086568Z","caller":"traceutil/trace.go:171","msg":"trace[865031951] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-g8clq; range_end:; response_count:1; response_revision:5589; }","duration":"322.000338ms","start":"2026-09-01T04:31:23.764556Z","end":"2026-09-01T04:31:24.086556Z","steps":["trace[865031951] 'range keys from in-memory index tree' (duration: 320.79775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:31:24.086601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:31:23.764503Z","time spent":"322.0894ms","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":7687,"request content":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-g8clq\" "} {"level":"info","ts":"2026-09-01T04:31:33.837563Z","caller":"traceutil/trace.go:171","msg":"trace[1159096007] transaction","detail":"{read_only:false; response_revision:5786; number_of_response:1; }","duration":"163.835754ms","start":"2026-09-01T04:31:33.673676Z","end":"2026-09-01T04:31:33.837512Z","steps":["trace[1159096007] 'process raft request' (duration: 163.679681ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:34.155191Z","caller":"traceutil/trace.go:171","msg":"trace[1860371303] transaction","detail":"{read_only:false; response_revision:5787; number_of_response:1; }","duration":"208.494836ms","start":"2026-09-01T04:31:33.946667Z","end":"2026-09-01T04:31:34.155162Z","steps":["trace[1860371303] 'process raft request' (duration: 208.301162ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:34.38097Z","caller":"traceutil/trace.go:171","msg":"trace[1212502335] transaction","detail":"{read_only:false; response_revision:5788; number_of_response:1; }","duration":"220.263057ms","start":"2026-09-01T04:31:34.160674Z","end":"2026-09-01T04:31:34.380937Z","steps":["trace[1212502335] 'process raft request' (duration: 151.334236ms)","trace[1212502335] 'compare' (duration: 68.570893ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:31:34.381384Z","caller":"traceutil/trace.go:171","msg":"trace[428628520] linearizableReadLoop","detail":"{readStateIndex:6004; appliedIndex:6002; }","duration":"219.708165ms","start":"2026-09-01T04:31:34.16165Z","end":"2026-09-01T04:31:34.381358Z","steps":["trace[428628520] 'read index received' (duration: 150.325182ms)","trace[428628520] 'applied index is now lower than readState.Index' (duration: 69.381423ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:31:34.381416Z","caller":"traceutil/trace.go:171","msg":"trace[313000804] transaction","detail":"{read_only:false; response_revision:5789; number_of_response:1; }","duration":"220.673778ms","start":"2026-09-01T04:31:34.160683Z","end":"2026-09-01T04:31:34.381357Z","steps":["trace[313000804] 'process raft request' (duration: 220.141425ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:34.381523Z","caller":"traceutil/trace.go:171","msg":"trace[530299574] transaction","detail":"{read_only:false; response_revision:5791; number_of_response:1; }","duration":"218.471326ms","start":"2026-09-01T04:31:34.163033Z","end":"2026-09-01T04:31:34.381504Z","steps":["trace[530299574] 'process raft request' (duration: 218.353903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:31:34.381649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.996302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-01T04:31:34.381687Z","caller":"traceutil/trace.go:171","msg":"trace[1210426874] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5791; }","duration":"220.091324ms","start":"2026-09-01T04:31:34.161585Z","end":"2026-09-01T04:31:34.381676Z","steps":["trace[1210426874] 'agreement among raft nodes before linearized reading' (duration: 219.884229ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:34.381672Z","caller":"traceutil/trace.go:171","msg":"trace[189853283] transaction","detail":"{read_only:false; response_revision:5790; number_of_response:1; }","duration":"219.733846ms","start":"2026-09-01T04:31:34.161891Z","end":"2026-09-01T04:31:34.381625Z","steps":["trace[189853283] 'process raft request' (duration: 219.306546ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:34.381952Z","caller":"traceutil/trace.go:171","msg":"trace[828408339] transaction","detail":"{read_only:false; response_revision:5792; number_of_response:1; }","duration":"218.653861ms","start":"2026-09-01T04:31:34.163243Z","end":"2026-09-01T04:31:34.381897Z","steps":["trace[828408339] 'process raft request' (duration: 218.218071ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:34.382475Z","caller":"traceutil/trace.go:171","msg":"trace[1496348461] transaction","detail":"{read_only:false; response_revision:5793; number_of_response:1; }","duration":"218.446995ms","start":"2026-09-01T04:31:34.164007Z","end":"2026-09-01T04:31:34.382454Z","steps":["trace[1496348461] 'process raft request' (duration: 217.830811ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:34.382759Z","caller":"traceutil/trace.go:171","msg":"trace[1653642583] transaction","detail":"{read_only:false; response_revision:5794; number_of_response:1; }","duration":"217.673139ms","start":"2026-09-01T04:31:34.165069Z","end":"2026-09-01T04:31:34.382742Z","steps":["trace[1653642583] 'process raft request' (duration: 217.317931ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:34.382913Z","caller":"traceutil/trace.go:171","msg":"trace[802864837] transaction","detail":"{read_only:false; response_revision:5795; number_of_response:1; }","duration":"214.890534ms","start":"2026-09-01T04:31:34.168008Z","end":"2026-09-01T04:31:34.382899Z","steps":["trace[802864837] 'process raft request' (duration: 214.635959ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:34.383072Z","caller":"traceutil/trace.go:171","msg":"trace[1459157419] transaction","detail":"{read_only:false; response_revision:5797; number_of_response:1; }","duration":"119.671614ms","start":"2026-09-01T04:31:34.263378Z","end":"2026-09-01T04:31:34.383049Z","steps":["trace[1459157419] 'process raft request' (duration: 119.414098ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:34.383593Z","caller":"traceutil/trace.go:171","msg":"trace[1266354888] transaction","detail":"{read_only:false; response_revision:5796; number_of_response:1; }","duration":"121.368033ms","start":"2026-09-01T04:31:34.262197Z","end":"2026-09-01T04:31:34.383565Z","steps":["trace[1266354888] 'process raft request' (duration: 120.537375ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:39.291109Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2993} {"level":"info","ts":"2026-09-01T04:31:39.370805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2993,"took":"78.34951ms","hash":3485435161,"current-db-size-bytes":55205888,"current-db-size":"55 MB","current-db-size-in-use-bytes":32120832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T04:31:39.370884Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3485435161,"revision":2993,"compact-revision":-1} {"level":"warn","ts":"2026-09-01T04:32:35.445592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.933111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:32:35.445755Z","caller":"traceutil/trace.go:171","msg":"trace[1122780842] transaction","detail":"{read_only:false; response_revision:6351; number_of_response:1; }","duration":"220.586297ms","start":"2026-09-01T04:32:35.225125Z","end":"2026-09-01T04:32:35.445712Z","steps":["trace[1122780842] 'process raft request' (duration: 89.39576ms)","trace[1122780842] 'compare' (duration: 130.829349ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:35:56.434892Z","caller":"traceutil/trace.go:171","msg":"trace[400166551] transaction","detail":"{read_only:false; response_revision:7741; number_of_response:1; }","duration":"153.139584ms","start":"2026-09-01T04:35:56.281721Z","end":"2026-09-01T04:35:56.434861Z","steps":["trace[400166551] 'process raft request' (duration: 152.890049ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:36:39.298652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5885} {"level":"info","ts":"2026-09-01T04:36:39.392657Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5885,"took":"92.708321ms","hash":2490853347,"current-db-size-bytes":55205888,"current-db-size":"55 MB","current-db-size-in-use-bytes":26284032,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T04:36:39.392773Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2490853347,"revision":5885,"compact-revision":2993} {"level":"warn","ts":"2026-09-01T04:37:53.477529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.426241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.477672Z","caller":"traceutil/trace.go:171","msg":"trace[1319493525] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8667; }","duration":"597.547914ms","start":"2026-09-01T04:37:52.880061Z","end":"2026-09-01T04:37:53.477609Z","steps":["trace[1319493525] 'range keys from in-memory index tree' (duration: 597.301569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.477722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.880007Z","time spent":"597.700307ms","remote":"127.0.0.1:58408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 "} {"level":"warn","ts":"2026-09-01T04:37:53.477712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.556643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.47782Z","caller":"traceutil/trace.go:171","msg":"trace[762136290] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8667; }","duration":"597.757398ms","start":"2026-09-01T04:37:52.880037Z","end":"2026-09-01T04:37:53.477794Z","steps":["trace[762136290] 'range keys from in-memory index tree' (duration: 597.473362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.477894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.88002Z","time spent":"597.853071ms","remote":"127.0.0.1:58408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 "} {"level":"warn","ts":"2026-09-01T04:37:53.478014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.932122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.478062Z","caller":"traceutil/trace.go:171","msg":"trace[1942994516] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8667; }","duration":"598.007494ms","start":"2026-09-01T04:37:52.880044Z","end":"2026-09-01T04:37:53.478052Z","steps":["trace[1942994516] 'count revisions from in-memory index tree' (duration: 597.842091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.478091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.880032Z","time spent":"598.050045ms","remote":"127.0.0.1:58408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"warn","ts":"2026-09-01T04:37:53.478377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.906536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:37:53.478476Z","caller":"traceutil/trace.go:171","msg":"trace[468722281] linearizableReadLoop","detail":"{readStateIndex:8975; appliedIndex:8974; }","duration":"578.584133ms","start":"2026-09-01T04:37:52.899876Z","end":"2026-09-01T04:37:53.47846Z","steps":["trace[468722281] 'read index received' (duration: 369.441831ms)","trace[468722281] 'applied index is now lower than readState.Index' (duration: 209.140712ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:37:53.478593Z","caller":"traceutil/trace.go:171","msg":"trace[1434959782] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"598.493646ms","start":"2026-09-01T04:37:52.880082Z","end":"2026-09-01T04:37:53.478576Z","steps":["trace[1434959782] 'process raft request' (duration: 389.306482ms)","trace[1434959782] 'compare' (duration: 208.745023ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:37:53.47871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.88006Z","time spent":"598.603219ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:37:53.47888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.930982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.478936Z","caller":"traceutil/trace.go:171","msg":"trace[87045073] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8668; }","duration":"579.004164ms","start":"2026-09-01T04:37:52.899917Z","end":"2026-09-01T04:37:53.478921Z","steps":["trace[87045073] 'agreement among raft nodes before linearized reading' (duration: 578.911341ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.478982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.899912Z","time spent":"579.052635ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"warn","ts":"2026-09-01T04:37:53.479156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.057501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:37:53.479237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.626645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.479263Z","caller":"traceutil/trace.go:171","msg":"trace[1193214147] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8668; }","duration":"475.186045ms","start":"2026-09-01T04:37:53.004047Z","end":"2026-09-01T04:37:53.479233Z","steps":["trace[1193214147] 'agreement among raft nodes before linearized reading' (duration: 475.00342ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:37:53.47928Z","caller":"traceutil/trace.go:171","msg":"trace[1025959789] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8668; }","duration":"475.698986ms","start":"2026-09-01T04:37:53.003567Z","end":"2026-09-01T04:37:53.479266Z","steps":["trace[1025959789] 'agreement among raft nodes before linearized reading' (duration: 475.619195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:53.003555Z","time spent":"475.748408ms","remote":"127.0.0.1:58454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-09-01T04:37:53.479325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:53.00404Z","time spent":"475.268056ms","remote":"127.0.0.1:58454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 "} {"level":"warn","ts":"2026-09-01T04:37:53.479334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.824165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.479379Z","caller":"traceutil/trace.go:171","msg":"trace[604940610] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8668; }","duration":"408.903217ms","start":"2026-09-01T04:37:53.070463Z","end":"2026-09-01T04:37:53.479366Z","steps":["trace[604940610] 'agreement among raft nodes before linearized reading' (duration: 408.823275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:53.07044Z","time spent":"408.962438ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 "} {"level":"warn","ts":"2026-09-01T04:37:53.479455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.352365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:37:53.479509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.527588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:37:53.479496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.118715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-74687b9c89-bpr92.18d1187f9a7cbf75\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-09-01T04:37:53.479521Z","caller":"traceutil/trace.go:171","msg":"trace[812421666] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8668; }","duration":"424.455968ms","start":"2026-09-01T04:37:53.055047Z","end":"2026-09-01T04:37:53.479503Z","steps":["trace[812421666] 'agreement among raft nodes before linearized reading' (duration: 424.323455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.010343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.479567Z","caller":"traceutil/trace.go:171","msg":"trace[203350858] range","detail":"{range_begin:/registry/events/openstack/barbican-api-74687b9c89-bpr92.18d1187f9a7cbf75; range_end:; response_count:1; response_revision:8668; }","duration":"104.186167ms","start":"2026-09-01T04:37:53.375358Z","end":"2026-09-01T04:37:53.479544Z","steps":["trace[203350858] 'agreement among raft nodes before linearized reading' (duration: 103.946181ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:53.055028Z","time spent":"424.532269ms","remote":"127.0.0.1:58476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-09-01T04:37:53.479583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.105264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:37:53.479672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.215698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.479676Z","caller":"traceutil/trace.go:171","msg":"trace[661136620] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8668; }","duration":"441.199276ms","start":"2026-09-01T04:37:53.038419Z","end":"2026-09-01T04:37:53.479619Z","steps":["trace[661136620] 'agreement among raft nodes before linearized reading' (duration: 441.122305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.629469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-01T04:37:53.479627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.988073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.479721Z","caller":"traceutil/trace.go:171","msg":"trace[1412573234] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8668; }","duration":"220.6994ms","start":"2026-09-01T04:37:53.259011Z","end":"2026-09-01T04:37:53.47971Z","steps":["trace[1412573234] 'agreement among raft nodes before linearized reading' (duration: 220.542447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:53.038395Z","time spent":"441.313448ms","remote":"127.0.0.1:58466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 "} {"level":"warn","ts":"2026-09-01T04:37:53.479747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.880563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:37:53.479746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.140828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.479779Z","caller":"traceutil/trace.go:171","msg":"trace[772228855] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8668; }","duration":"554.942295ms","start":"2026-09-01T04:37:52.924826Z","end":"2026-09-01T04:37:53.479769Z","steps":["trace[772228855] 'agreement among raft nodes before linearized reading' (duration: 554.902064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.239505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:37:53.47981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.924809Z","time spent":"554.993625ms","remote":"127.0.0.1:58424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 "} {"level":"info","ts":"2026-09-01T04:37:53.479802Z","caller":"traceutil/trace.go:171","msg":"trace[920885823] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8668; }","duration":"476.160677ms","start":"2026-09-01T04:37:53.003575Z","end":"2026-09-01T04:37:53.479736Z","steps":["trace[920885823] 'agreement among raft nodes before linearized reading' (duration: 475.962452ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:37:53.479819Z","caller":"traceutil/trace.go:171","msg":"trace[1071516990] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8668; }","duration":"442.236819ms","start":"2026-09-01T04:37:53.03756Z","end":"2026-09-01T04:37:53.479796Z","steps":["trace[1071516990] 'agreement among raft nodes before linearized reading' (duration: 442.122137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.47946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.887893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.479863Z","caller":"traceutil/trace.go:171","msg":"trace[624465719] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8668; }","duration":"533.314723ms","start":"2026-09-01T04:37:52.94654Z","end":"2026-09-01T04:37:53.479855Z","steps":["trace[624465719] 'agreement among raft nodes before linearized reading' (duration: 532.783301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.47987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.993006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:37:53.47987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:53.037548Z","time spent":"442.306971ms","remote":"127.0.0.1:58466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 "} {"level":"warn","ts":"2026-09-01T04:37:53.479874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.663289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:37:53.479891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.946493Z","time spent":"533.388835ms","remote":"127.0.0.1:58440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 "} {"level":"info","ts":"2026-09-01T04:37:53.479899Z","caller":"traceutil/trace.go:171","msg":"trace[354974626] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8668; }","duration":"580.051447ms","start":"2026-09-01T04:37:52.899839Z","end":"2026-09-01T04:37:53.479891Z","steps":["trace[354974626] 'agreement among raft nodes before linearized reading' (duration: 580.007166ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:53.003524Z","time spent":"476.32175ms","remote":"127.0.0.1:58454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 "} {"level":"info","ts":"2026-09-01T04:37:53.479924Z","caller":"traceutil/trace.go:171","msg":"trace[721844558] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8668; }","duration":"423.755361ms","start":"2026-09-01T04:37:53.056154Z","end":"2026-09-01T04:37:53.479909Z","steps":["trace[721844558] 'agreement among raft nodes before linearized reading' (duration: 423.676909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.899825Z","time spent":"580.094668ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 "} {"level":"info","ts":"2026-09-01T04:37:53.479595Z","caller":"traceutil/trace.go:171","msg":"trace[1099081859] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8668; }","duration":"579.060024ms","start":"2026-09-01T04:37:52.900527Z","end":"2026-09-01T04:37:53.479587Z","steps":["trace[1099081859] 'agreement among raft nodes before linearized reading' (duration: 579.023593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:53.05613Z","time spent":"423.824613ms","remote":"127.0.0.1:58476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 "} {"level":"info","ts":"2026-09-01T04:37:53.479825Z","caller":"traceutil/trace.go:171","msg":"trace[94606966] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8668; }","duration":"409.280566ms","start":"2026-09-01T04:37:53.070531Z","end":"2026-09-01T04:37:53.479812Z","steps":["trace[94606966] 'agreement among raft nodes before linearized reading' (duration: 409.235645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.935661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-01T04:37:53.480019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:53.070528Z","time spent":"409.475891ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"info","ts":"2026-09-01T04:37:53.480047Z","caller":"traceutil/trace.go:171","msg":"trace[1516014231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8668; }","duration":"104.173388ms","start":"2026-09-01T04:37:53.375862Z","end":"2026-09-01T04:37:53.480035Z","steps":["trace[1516014231] 'agreement among raft nodes before linearized reading' (duration: 103.89392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.480098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.742607ms","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:5"} {"level":"warn","ts":"2026-09-01T04:37:53.479738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.196914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:37:53.480147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.482316ms","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:5"} {"level":"warn","ts":"2026-09-01T04:37:53.480162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.026841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.480172Z","caller":"traceutil/trace.go:171","msg":"trace[69856994] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8668; }","duration":"409.628554ms","start":"2026-09-01T04:37:53.070524Z","end":"2026-09-01T04:37:53.480152Z","steps":["trace[69856994] 'agreement among raft nodes before linearized reading' (duration: 409.172673ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:37:53.480176Z","caller":"traceutil/trace.go:171","msg":"trace[1486474707] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8668; }","duration":"442.555188ms","start":"2026-09-01T04:37:53.037611Z","end":"2026-09-01T04:37:53.480166Z","steps":["trace[1486474707] 'agreement among raft nodes before linearized reading' (duration: 442.508517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.48015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.031691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:37:53.480221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.181695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.480131Z","caller":"traceutil/trace.go:171","msg":"trace[1883532442] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8668; }","duration":"506.796679ms","start":"2026-09-01T04:37:52.973323Z","end":"2026-09-01T04:37:53.480119Z","steps":["trace[1883532442] 'agreement among raft nodes before linearized reading' (duration: 506.745247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.463287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:37:53.48023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:53.070509Z","time spent":"409.702416ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 "} {"level":"info","ts":"2026-09-01T04:37:53.480246Z","caller":"traceutil/trace.go:171","msg":"trace[1635153350] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8668; }","duration":"506.234175ms","start":"2026-09-01T04:37:52.974004Z","end":"2026-09-01T04:37:53.480238Z","steps":["trace[1635153350] 'agreement among raft nodes before linearized reading' (duration: 506.199924ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:37:53.47971Z","caller":"traceutil/trace.go:171","msg":"trace[2073944884] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8668; }","duration":"554.28271ms","start":"2026-09-01T04:37:52.925412Z","end":"2026-09-01T04:37:53.479695Z","steps":["trace[2073944884] 'agreement among raft nodes before linearized reading' (duration: 554.203008ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:37:53.480266Z","caller":"traceutil/trace.go:171","msg":"trace[9492955] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8668; }","duration":"534.253646ms","start":"2026-09-01T04:37:52.946Z","end":"2026-09-01T04:37:53.480254Z","steps":["trace[9492955] 'agreement among raft nodes before linearized reading' (duration: 533.447536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.480222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:53.037604Z","time spent":"442.610699ms","remote":"127.0.0.1:58466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"info","ts":"2026-09-01T04:37:53.480216Z","caller":"traceutil/trace.go:171","msg":"trace[1643717601] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8668; }","duration":"425.084502ms","start":"2026-09-01T04:37:53.055117Z","end":"2026-09-01T04:37:53.480202Z","steps":["trace[1643717601] 'agreement among raft nodes before linearized reading' (duration: 425.003771ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.48028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.904101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:37:53.480244Z","caller":"traceutil/trace.go:171","msg":"trace[217621234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8668; }","duration":"531.151474ms","start":"2026-09-01T04:37:52.949077Z","end":"2026-09-01T04:37:53.480229Z","steps":["trace[217621234] 'agreement among raft nodes before linearized reading' (duration: 531.028161ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:37:53.480315Z","caller":"traceutil/trace.go:171","msg":"trace[922654087] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8668; }","duration":"506.940222ms","start":"2026-09-01T04:37:52.973366Z","end":"2026-09-01T04:37:53.480306Z","steps":["trace[922654087] 'agreement among raft nodes before linearized reading' (duration: 506.887231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.48031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.945988Z","time spent":"534.306906ms","remote":"127.0.0.1:58440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 "} {"level":"warn","ts":"2026-09-01T04:37:53.480272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.973988Z","time spent":"506.276737ms","remote":"127.0.0.1:58442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 "} {"level":"warn","ts":"2026-09-01T04:37:53.48034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.973335Z","time spent":"506.997512ms","remote":"127.0.0.1:58442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 "} {"level":"warn","ts":"2026-09-01T04:37:53.48033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.925394Z","time spent":"554.899054ms","remote":"127.0.0.1:58424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 "} {"level":"warn","ts":"2026-09-01T04:37:53.480336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.949064Z","time spent":"531.258176ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T04:37:53.48037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:53.055103Z","time spent":"425.206976ms","remote":"127.0.0.1:58476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 "} {"level":"warn","ts":"2026-09-01T04:37:53.48019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.589553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n8nrc\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-01T04:37:53.479535Z","caller":"traceutil/trace.go:171","msg":"trace[989732471] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8668; }","duration":"533.552988ms","start":"2026-09-01T04:37:52.945973Z","end":"2026-09-01T04:37:53.479526Z","steps":["trace[989732471] 'agreement among raft nodes before linearized reading' (duration: 533.519107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.480254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.973311Z","time spent":"506.936021ms","remote":"127.0.0.1:58442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-09-01T04:37:53.480503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.945928Z","time spent":"534.563462ms","remote":"127.0.0.1:58440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"info","ts":"2026-09-01T04:37:53.480472Z","caller":"traceutil/trace.go:171","msg":"trace[261066314] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n8nrc; range_end:; response_count:1; response_revision:8668; }","duration":"490.898399ms","start":"2026-09-01T04:37:52.989557Z","end":"2026-09-01T04:37:53.480455Z","steps":["trace[261066314] 'agreement among raft nodes before linearized reading' (duration: 490.589062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.479585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.7293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:37:53.480573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.989538Z","time spent":"491.020362ms","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6469,"request content":"key:\"/registry/pods/openstack/keepalived-n8nrc\" "} {"level":"info","ts":"2026-09-01T04:37:53.480608Z","caller":"traceutil/trace.go:171","msg":"trace[1609065888] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8668; }","duration":"555.747834ms","start":"2026-09-01T04:37:52.924837Z","end":"2026-09-01T04:37:53.480584Z","steps":["trace[1609065888] 'agreement among raft nodes before linearized reading' (duration: 554.699549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:37:53.480011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.900515Z","time spent":"579.473955ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 "} {"level":"warn","ts":"2026-09-01T04:37:53.480709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:37:52.924777Z","time spent":"555.917637ms","remote":"127.0.0.1:58424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"info","ts":"2026-09-01T04:38:04.229806Z","caller":"traceutil/trace.go:171","msg":"trace[327398309] linearizableReadLoop","detail":"{readStateIndex:9037; appliedIndex:9036; }","duration":"136.020416ms","start":"2026-09-01T04:38:04.093756Z","end":"2026-09-01T04:38:04.229776Z","steps":["trace[327398309] 'read index received' (duration: 135.850302ms)","trace[327398309] 'applied index is now lower than readState.Index' (duration: 168.934µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:38:04.22988Z","caller":"traceutil/trace.go:171","msg":"trace[1726503613] transaction","detail":"{read_only:false; response_revision:8728; number_of_response:1; }","duration":"162.975941ms","start":"2026-09-01T04:38:04.066864Z","end":"2026-09-01T04:38:04.229839Z","steps":["trace[1726503613] 'process raft request' (duration: 162.718185ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:38:04.230086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.320012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20355"} {"level":"info","ts":"2026-09-01T04:38:04.230138Z","caller":"traceutil/trace.go:171","msg":"trace[916547803] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8728; }","duration":"136.422244ms","start":"2026-09-01T04:38:04.093702Z","end":"2026-09-01T04:38:04.230124Z","steps":["trace[916547803] 'agreement among raft nodes before linearized reading' (duration: 136.194129ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:38:04.429482Z","caller":"traceutil/trace.go:171","msg":"trace[2020552859] linearizableReadLoop","detail":"{readStateIndex:9038; appliedIndex:9037; }","duration":"194.616685ms","start":"2026-09-01T04:38:04.234845Z","end":"2026-09-01T04:38:04.429462Z","steps":["trace[2020552859] 'read index received' (duration: 122.668755ms)","trace[2020552859] 'applied index is now lower than readState.Index' (duration: 71.94709ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:38:04.429549Z","caller":"traceutil/trace.go:171","msg":"trace[1136630369] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"269.515593ms","start":"2026-09-01T04:38:04.160004Z","end":"2026-09-01T04:38:04.42952Z","steps":["trace[1136630369] 'process raft request' (duration: 197.522873ms)","trace[1136630369] 'compare' (duration: 71.839067ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:38:04.429654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.784228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20355"} {"level":"info","ts":"2026-09-01T04:38:04.429681Z","caller":"traceutil/trace.go:171","msg":"trace[1920746424] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8729; }","duration":"194.874621ms","start":"2026-09-01T04:38:04.234798Z","end":"2026-09-01T04:38:04.429672Z","steps":["trace[1920746424] 'agreement among raft nodes before linearized reading' (duration: 194.736748ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:38:04.597566Z","caller":"traceutil/trace.go:171","msg":"trace[1952011950] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"194.981634ms","start":"2026-09-01T04:38:04.402556Z","end":"2026-09-01T04:38:04.597538Z","steps":["trace[1952011950] 'process raft request' (duration: 138.331389ms)","trace[1952011950] 'compare' (duration: 56.535312ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:38:35.955587Z","caller":"traceutil/trace.go:171","msg":"trace[1965640901] linearizableReadLoop","detail":"{readStateIndex:9267; appliedIndex:9266; }","duration":"157.236166ms","start":"2026-09-01T04:38:35.798322Z","end":"2026-09-01T04:38:35.955558Z","steps":["trace[1965640901] 'read index received' (duration: 157.036981ms)","trace[1965640901] 'applied index is now lower than readState.Index' (duration: 197.945µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:38:35.955756Z","caller":"traceutil/trace.go:171","msg":"trace[402518] transaction","detail":"{read_only:false; response_revision:8949; number_of_response:1; }","duration":"295.4418ms","start":"2026-09-01T04:38:35.660297Z","end":"2026-09-01T04:38:35.955738Z","steps":["trace[402518] 'process raft request' (duration: 295.087142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:38:35.956039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.706677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-01T04:38:35.956079Z","caller":"traceutil/trace.go:171","msg":"trace[1594977913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8949; }","duration":"157.825279ms","start":"2026-09-01T04:38:35.798241Z","end":"2026-09-01T04:38:35.956066Z","steps":["trace[1594977913] 'agreement among raft nodes before linearized reading' (duration: 157.660765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:38:35.956248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.91499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n8nrc\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-01T04:38:35.956331Z","caller":"traceutil/trace.go:171","msg":"trace[511254934] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n8nrc; range_end:; response_count:1; response_revision:8949; }","duration":"114.079603ms","start":"2026-09-01T04:38:35.84223Z","end":"2026-09-01T04:38:35.95631Z","steps":["trace[511254934] 'agreement among raft nodes before linearized reading' (duration: 113.94216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:38:35.958147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.882591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T04:38:35.95823Z","caller":"traceutil/trace.go:171","msg":"trace[1002204844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8949; }","duration":"159.872577ms","start":"2026-09-01T04:38:35.798339Z","end":"2026-09-01T04:38:35.958211Z","steps":["trace[1002204844] 'agreement among raft nodes before linearized reading' (duration: 157.801439ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:38:36.232095Z","caller":"traceutil/trace.go:171","msg":"trace[859236139] transaction","detail":"{read_only:false; response_revision:8950; number_of_response:1; }","duration":"270.599543ms","start":"2026-09-01T04:38:35.961459Z","end":"2026-09-01T04:38:36.232058Z","steps":["trace[859236139] 'process raft request' (duration: 212.402669ms)","trace[859236139] 'compare' (duration: 58.04489ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:38:36.233126Z","caller":"traceutil/trace.go:171","msg":"trace[67261432] transaction","detail":"{read_only:false; response_revision:8951; number_of_response:1; }","duration":"269.849014ms","start":"2026-09-01T04:38:35.963252Z","end":"2026-09-01T04:38:36.233101Z","steps":["trace[67261432] 'process raft request' (duration: 269.633599ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:38:36.233301Z","caller":"traceutil/trace.go:171","msg":"trace[1182842755] linearizableReadLoop","detail":"{readStateIndex:9270; appliedIndex:9267; }","duration":"122.12429ms","start":"2026-09-01T04:38:36.111146Z","end":"2026-09-01T04:38:36.233271Z","steps":["trace[1182842755] 'read index received' (duration: 62.728698ms)","trace[1182842755] 'applied index is now lower than readState.Index' (duration: 59.393582ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:38:36.233529Z","caller":"traceutil/trace.go:171","msg":"trace[1283905859] transaction","detail":"{read_only:false; response_revision:8952; number_of_response:1; }","duration":"164.094486ms","start":"2026-09-01T04:38:36.069418Z","end":"2026-09-01T04:38:36.233512Z","steps":["trace[1283905859] 'process raft request' (duration: 163.631064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:38:36.23353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.362235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-01T04:38:36.233952Z","caller":"traceutil/trace.go:171","msg":"trace[1753468484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8952; }","duration":"122.796196ms","start":"2026-09-01T04:38:36.111138Z","end":"2026-09-01T04:38:36.233934Z","steps":["trace[1753468484] 'agreement among raft nodes before linearized reading' (duration: 122.223803ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:38:40.228232Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000244a.wal"} {"level":"info","ts":"2026-09-01T04:38:45.13384Z","caller":"traceutil/trace.go:171","msg":"trace[381053732] transaction","detail":"{read_only:false; response_revision:8998; number_of_response:1; }","duration":"120.048801ms","start":"2026-09-01T04:38:45.013756Z","end":"2026-09-01T04:38:45.133804Z","steps":["trace[381053732] 'process raft request' (duration: 119.795645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:38:45.355951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.832414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:38:45.356239Z","caller":"traceutil/trace.go:171","msg":"trace[1447687746] transaction","detail":"{read_only:false; response_revision:8999; number_of_response:1; }","duration":"341.91208ms","start":"2026-09-01T04:38:45.014287Z","end":"2026-09-01T04:38:45.356199Z","steps":["trace[1447687746] 'process raft request' (duration: 237.768449ms)","trace[1447687746] 'compare' (duration: 103.731402ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:38:45.356448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:38:45.014241Z","time spent":"342.105094ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:38:49.008959Z","caller":"traceutil/trace.go:171","msg":"trace[1121601570] transaction","detail":"{read_only:false; response_revision:9029; number_of_response:1; }","duration":"123.517111ms","start":"2026-09-01T04:38:48.885412Z","end":"2026-09-01T04:38:49.008929Z","steps":["trace[1121601570] 'process raft request' (duration: 87.428412ms)","trace[1121601570] 'compare' (duration: 35.730781ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:38:49.009307Z","caller":"traceutil/trace.go:171","msg":"trace[1827200538] linearizableReadLoop","detail":"{readStateIndex:9352; appliedIndex:9350; }","duration":"116.322255ms","start":"2026-09-01T04:38:48.892972Z","end":"2026-09-01T04:38:49.009295Z","steps":["trace[1827200538] 'read index received' (duration: 80.061822ms)","trace[1827200538] 'applied index is now lower than readState.Index' (duration: 36.259533ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:38:49.00942Z","caller":"traceutil/trace.go:171","msg":"trace[91081902] transaction","detail":"{read_only:false; response_revision:9031; number_of_response:1; }","duration":"112.853473ms","start":"2026-09-01T04:38:48.896557Z","end":"2026-09-01T04:38:49.00941Z","steps":["trace[91081902] 'process raft request' (duration: 112.670629ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:38:49.00961Z","caller":"traceutil/trace.go:171","msg":"trace[1819535410] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9030; }","duration":"123.569314ms","start":"2026-09-01T04:38:48.885986Z","end":"2026-09-01T04:38:49.009555Z","steps":["trace[1819535410] 'process raft request' (duration: 122.904238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:38:49.009682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.661063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-09-01T04:38:49.010138Z","caller":"traceutil/trace.go:171","msg":"trace[298463727] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:9031; }","duration":"117.162864ms","start":"2026-09-01T04:38:48.892916Z","end":"2026-09-01T04:38:49.010079Z","steps":["trace[298463727] 'agreement among raft nodes before linearized reading' (duration: 116.570011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:38:49.278828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.178145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-01T04:38:49.279062Z","caller":"traceutil/trace.go:171","msg":"trace[1650819191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9031; }","duration":"120.498201ms","start":"2026-09-01T04:38:49.158539Z","end":"2026-09-01T04:38:49.279038Z","steps":["trace[1650819191] 'range keys from in-memory index tree' (duration: 119.797886ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:39:48.402206Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"373d5d49ce4adaff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T04:39:48.408801Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T04:39:48.408885Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-01T04:40:55.193302Z","caller":"traceutil/trace.go:171","msg":"trace[1743987676] linearizableReadLoop","detail":"{readStateIndex:10499; appliedIndex:10498; }","duration":"208.212145ms","start":"2026-09-01T04:40:54.98504Z","end":"2026-09-01T04:40:55.193252Z","steps":["trace[1743987676] 'read index received' (duration: 207.792594ms)","trace[1743987676] 'applied index is now lower than readState.Index' (duration: 418.011µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:40:55.193346Z","caller":"traceutil/trace.go:171","msg":"trace[336429961] transaction","detail":"{read_only:false; response_revision:10145; number_of_response:1; }","duration":"208.746717ms","start":"2026-09-01T04:40:54.984553Z","end":"2026-09-01T04:40:55.1933Z","steps":["trace[336429961] 'process raft request' (duration: 208.208194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:40:55.193924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.867131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:34 size:319380"} {"level":"info","ts":"2026-09-01T04:40:55.193983Z","caller":"traceutil/trace.go:171","msg":"trace[519622389] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:34; response_revision:10145; }","duration":"208.983023ms","start":"2026-09-01T04:40:54.984988Z","end":"2026-09-01T04:40:55.193971Z","steps":["trace[519622389] 'agreement among raft nodes before linearized reading' (duration: 208.446031ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:40:55.336525Z","caller":"traceutil/trace.go:171","msg":"trace[385179421] linearizableReadLoop","detail":"{readStateIndex:10500; appliedIndex:10499; }","duration":"142.950547ms","start":"2026-09-01T04:40:55.193546Z","end":"2026-09-01T04:40:55.336496Z","steps":["trace[385179421] 'read index received' (duration: 114.917287ms)","trace[385179421] 'applied index is now lower than readState.Index' (duration: 28.03214ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:40:55.336767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.734487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-01T04:40:55.336776Z","caller":"traceutil/trace.go:171","msg":"trace[1295373256] transaction","detail":"{read_only:false; response_revision:10146; number_of_response:1; }","duration":"322.427825ms","start":"2026-09-01T04:40:55.014323Z","end":"2026-09-01T04:40:55.33675Z","steps":["trace[1295373256] 'process raft request' (duration: 294.108928ms)","trace[1295373256] 'compare' (duration: 27.922008ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:40:55.336816Z","caller":"traceutil/trace.go:171","msg":"trace[1263383597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10146; }","duration":"340.811708ms","start":"2026-09-01T04:40:54.995991Z","end":"2026-09-01T04:40:55.336803Z","steps":["trace[1263383597] 'agreement among raft nodes before linearized reading' (duration: 340.602813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:40:55.336792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.264923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:40:55.336853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.341209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"warn","ts":"2026-09-01T04:40:55.336808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.445387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T04:40:55.336924Z","caller":"traceutil/trace.go:171","msg":"trace[1794162961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10146; }","duration":"286.404357ms","start":"2026-09-01T04:40:55.050472Z","end":"2026-09-01T04:40:55.336876Z","steps":["trace[1794162961] 'agreement among raft nodes before linearized reading' (duration: 286.229753ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:40:55.336959Z","caller":"traceutil/trace.go:171","msg":"trace[1020563686] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:10146; }","duration":"269.665712ms","start":"2026-09-01T04:40:55.067265Z","end":"2026-09-01T04:40:55.336931Z","steps":["trace[1020563686] 'agreement among raft nodes before linearized reading' (duration: 269.325914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:40:55.336846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:40:54.995981Z","time spent":"340.856589ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-01T04:40:55.33689Z","caller":"traceutil/trace.go:171","msg":"trace[1820020288] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:10146; }","duration":"197.403661ms","start":"2026-09-01T04:40:55.139476Z","end":"2026-09-01T04:40:55.336879Z","steps":["trace[1820020288] 'agreement among raft nodes before linearized reading' (duration: 197.325499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:40:55.336892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:40:55.014307Z","time spent":"322.514178ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:40:55.336772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.2784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T04:40:55.337441Z","caller":"traceutil/trace.go:171","msg":"trace[1090360033] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10146; }","duration":"282.990356ms","start":"2026-09-01T04:40:55.054431Z","end":"2026-09-01T04:40:55.337422Z","steps":["trace[1090360033] 'agreement among raft nodes before linearized reading' (duration: 282.181177ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:41:39.306082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8046} {"level":"info","ts":"2026-09-01T04:41:39.377317Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8046,"took":"68.900674ms","hash":3112018266,"current-db-size-bytes":55205888,"current-db-size":"55 MB","current-db-size-in-use-bytes":28196864,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T04:41:39.37736Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3112018266,"revision":8046,"compact-revision":5885} {"level":"info","ts":"2026-09-01T04:45:54.641552Z","caller":"traceutil/trace.go:171","msg":"trace[1646706691] transaction","detail":"{read_only:false; response_revision:12296; number_of_response:1; }","duration":"138.482213ms","start":"2026-09-01T04:45:54.503047Z","end":"2026-09-01T04:45:54.641529Z","steps":["trace[1646706691] 'process raft request' (duration: 138.279968ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:45:56.088579Z","caller":"traceutil/trace.go:171","msg":"trace[148760215] transaction","detail":"{read_only:false; response_revision:12303; number_of_response:1; }","duration":"311.456975ms","start":"2026-09-01T04:45:55.777085Z","end":"2026-09-01T04:45:56.088542Z","steps":["trace[148760215] 'process raft request' (duration: 258.546956ms)","trace[148760215] 'compare' (duration: 52.54647ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:45:56.088639Z","caller":"traceutil/trace.go:171","msg":"trace[1139592531] transaction","detail":"{read_only:false; response_revision:12304; number_of_response:1; }","duration":"311.151377ms","start":"2026-09-01T04:45:55.777455Z","end":"2026-09-01T04:45:56.088606Z","steps":["trace[1139592531] 'process raft request' (duration: 311.016764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:45:56.08872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:45:55.777057Z","time spent":"311.592678ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:45:56.088825Z","caller":"traceutil/trace.go:171","msg":"trace[1967327228] linearizableReadLoop","detail":"{readStateIndex:12728; appliedIndex:12726; }","duration":"140.811888ms","start":"2026-09-01T04:45:55.947971Z","end":"2026-09-01T04:45:56.088783Z","steps":["trace[1967327228] 'read index received' (duration: 87.644993ms)","trace[1967327228] 'applied index is now lower than readState.Index' (duration: 53.163925ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:45:56.08885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:45:55.777432Z","time spent":"311.289621ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:45:56.088873Z","caller":"traceutil/trace.go:171","msg":"trace[1063607243] transaction","detail":"{read_only:false; response_revision:12305; number_of_response:1; }","duration":"131.576672ms","start":"2026-09-01T04:45:55.957277Z","end":"2026-09-01T04:45:56.088854Z","steps":["trace[1063607243] 'process raft request' (duration: 131.274915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:45:56.088963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.978812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:45:56.088995Z","caller":"traceutil/trace.go:171","msg":"trace[361116216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12305; }","duration":"141.071454ms","start":"2026-09-01T04:45:55.947913Z","end":"2026-09-01T04:45:56.088985Z","steps":["trace[361116216] 'agreement among raft nodes before linearized reading' (duration: 140.983972ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:46:39.313797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10434} {"level":"info","ts":"2026-09-01T04:46:39.413564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10434,"took":"97.134434ms","hash":4075153752,"current-db-size-bytes":55205888,"current-db-size":"55 MB","current-db-size-in-use-bytes":25985024,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T04:46:39.413648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4075153752,"revision":10434,"compact-revision":8046} {"level":"warn","ts":"2026-09-01T04:46:58.316346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.889392ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-01T04:51:39.32098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12617} {"level":"info","ts":"2026-09-01T04:51:39.410304Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12617,"took":"87.17378ms","hash":2168934748,"current-db-size-bytes":55205888,"current-db-size":"55 MB","current-db-size-in-use-bytes":27783168,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T04:51:39.410384Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2168934748,"revision":12617,"compact-revision":10434} {"level":"info","ts":"2026-09-01T04:52:01.724681Z","caller":"traceutil/trace.go:171","msg":"trace[1944825668] linearizableReadLoop","detail":"{readStateIndex:15290; appliedIndex:15289; }","duration":"227.646973ms","start":"2026-09-01T04:52:01.497003Z","end":"2026-09-01T04:52:01.72465Z","steps":["trace[1944825668] 'read index received' (duration: 227.598371ms)","trace[1944825668] 'applied index is now lower than readState.Index' (duration: 47.181µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:52:01.724744Z","caller":"traceutil/trace.go:171","msg":"trace[1029106693] transaction","detail":"{read_only:false; response_revision:14779; number_of_response:1; }","duration":"251.230702ms","start":"2026-09-01T04:52:01.473492Z","end":"2026-09-01T04:52:01.724723Z","steps":["trace[1029106693] 'process raft request' (duration: 250.987967ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:52:01.7249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.883819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:52:01.724944Z","caller":"traceutil/trace.go:171","msg":"trace[625046086] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14779; }","duration":"227.984941ms","start":"2026-09-01T04:52:01.496944Z","end":"2026-09-01T04:52:01.724929Z","steps":["trace[625046086] 'agreement among raft nodes before linearized reading' (duration: 227.893449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:52:01.750334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.728964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T04:52:01.750419Z","caller":"traceutil/trace.go:171","msg":"trace[1530221001] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14780; }","duration":"120.836136ms","start":"2026-09-01T04:52:01.629562Z","end":"2026-09-01T04:52:01.750398Z","steps":["trace[1530221001] 'agreement among raft nodes before linearized reading' (duration: 120.665762ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:52:34.747473Z","caller":"traceutil/trace.go:171","msg":"trace[1082623496] transaction","detail":"{read_only:false; response_revision:14950; number_of_response:1; }","duration":"174.072766ms","start":"2026-09-01T04:52:34.573359Z","end":"2026-09-01T04:52:34.747432Z","steps":["trace[1082623496] 'process raft request' (duration: 171.718991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:52:43.592522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.960463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:52:43.592708Z","caller":"traceutil/trace.go:171","msg":"trace[57297735] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:15009; }","duration":"174.205748ms","start":"2026-09-01T04:52:43.418471Z","end":"2026-09-01T04:52:43.592677Z","steps":["trace[57297735] 'count revisions from in-memory index tree' (duration: 173.86929ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:52:43.59401Z","caller":"traceutil/trace.go:171","msg":"trace[1639016551] transaction","detail":"{read_only:false; response_revision:15010; number_of_response:1; }","duration":"175.300894ms","start":"2026-09-01T04:52:43.418681Z","end":"2026-09-01T04:52:43.593982Z","steps":["trace[1639016551] 'process raft request' (duration: 175.14721ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:52:43.598616Z","caller":"traceutil/trace.go:171","msg":"trace[300902621] linearizableReadLoop","detail":"{readStateIndex:15534; appliedIndex:15532; }","duration":"128.037072ms","start":"2026-09-01T04:52:43.470555Z","end":"2026-09-01T04:52:43.598592Z","steps":["trace[300902621] 'read index received' (duration: 123.464266ms)","trace[300902621] 'applied index is now lower than readState.Index' (duration: 4.571886ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:52:43.598659Z","caller":"traceutil/trace.go:171","msg":"trace[1950781304] transaction","detail":"{read_only:false; response_revision:15011; number_of_response:1; }","duration":"179.87007ms","start":"2026-09-01T04:52:43.418754Z","end":"2026-09-01T04:52:43.598624Z","steps":["trace[1950781304] 'process raft request' (duration: 179.681706ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:52:43.598741Z","caller":"traceutil/trace.go:171","msg":"trace[2096840629] transaction","detail":"{read_only:false; response_revision:15012; number_of_response:1; }","duration":"150.510476ms","start":"2026-09-01T04:52:43.448219Z","end":"2026-09-01T04:52:43.59873Z","steps":["trace[2096840629] 'process raft request' (duration: 150.321982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:52:43.59897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.37746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T04:52:43.599074Z","caller":"traceutil/trace.go:171","msg":"trace[411447079] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15012; }","duration":"128.532495ms","start":"2026-09-01T04:52:43.470518Z","end":"2026-09-01T04:52:43.599051Z","steps":["trace[411447079] 'agreement among raft nodes before linearized reading' (duration: 128.331699ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:53:07.663095Z","caller":"traceutil/trace.go:171","msg":"trace[1890620636] transaction","detail":"{read_only:false; response_revision:15327; number_of_response:1; }","duration":"142.052579ms","start":"2026-09-01T04:53:07.521022Z","end":"2026-09-01T04:53:07.663074Z","steps":["trace[1890620636] 'process raft request' (duration: 141.922476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:53:20.0487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.171772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-01T04:53:20.049056Z","caller":"traceutil/trace.go:171","msg":"trace[439986627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15417; }","duration":"202.576592ms","start":"2026-09-01T04:53:19.846453Z","end":"2026-09-01T04:53:20.04903Z","steps":["trace[439986627] 'range keys from in-memory index tree' (duration: 201.768063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:53:20.048789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.168476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-24zkr\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-09-01T04:53:20.049218Z","caller":"traceutil/trace.go:171","msg":"trace[1558935792] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-24zkr; range_end:; response_count:1; response_revision:15417; }","duration":"155.580716ms","start":"2026-09-01T04:53:19.893545Z","end":"2026-09-01T04:53:20.049126Z","steps":["trace[1558935792] 'range keys from in-memory index tree' (duration: 154.770857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:53:20.04886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.439518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T04:53:20.04935Z","caller":"traceutil/trace.go:171","msg":"trace[1949489310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15417; }","duration":"236.002041ms","start":"2026-09-01T04:53:19.813322Z","end":"2026-09-01T04:53:20.049324Z","steps":["trace[1949489310] 'range keys from in-memory index tree' (duration: 235.018048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:53:20.321559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.766783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:53:20.321651Z","caller":"traceutil/trace.go:171","msg":"trace[425415418] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:15421; }","duration":"147.919497ms","start":"2026-09-01T04:53:20.173709Z","end":"2026-09-01T04:53:20.321628Z","steps":["trace[425415418] 'count revisions from in-memory index tree' (duration: 147.648661ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:54:00.976056Z","caller":"traceutil/trace.go:171","msg":"trace[1608254917] transaction","detail":"{read_only:false; response_revision:15960; number_of_response:1; }","duration":"254.304187ms","start":"2026-09-01T04:54:00.721728Z","end":"2026-09-01T04:54:00.976033Z","steps":["trace[1608254917] 'process raft request' (duration: 254.147743ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:54:00.992262Z","caller":"traceutil/trace.go:171","msg":"trace[2062473188] linearizableReadLoop","detail":"{readStateIndex:16513; appliedIndex:16511; }","duration":"220.51024ms","start":"2026-09-01T04:54:00.771717Z","end":"2026-09-01T04:54:00.992227Z","steps":["trace[2062473188] 'read index received' (duration: 204.171209ms)","trace[2062473188] 'applied index is now lower than readState.Index' (duration: 16.337751ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:54:00.992416Z","caller":"traceutil/trace.go:171","msg":"trace[1018814854] transaction","detail":"{read_only:false; response_revision:15961; number_of_response:1; }","duration":"237.012554ms","start":"2026-09-01T04:54:00.755387Z","end":"2026-09-01T04:54:00.992399Z","steps":["trace[1018814854] 'process raft request' (duration: 236.718947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:54:00.992592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.318274ms","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-09-01T04:54:00.992653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.019855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-01T04:54:00.992678Z","caller":"traceutil/trace.go:171","msg":"trace[1036041641] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15961; }","duration":"220.441708ms","start":"2026-09-01T04:54:00.772219Z","end":"2026-09-01T04:54:00.992661Z","steps":["trace[1036041641] 'agreement among raft nodes before linearized reading' (duration: 220.309495ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:54:00.992696Z","caller":"traceutil/trace.go:171","msg":"trace[2048259153] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15961; }","duration":"213.072186ms","start":"2026-09-01T04:54:00.779611Z","end":"2026-09-01T04:54:00.992684Z","steps":["trace[2048259153] 'agreement among raft nodes before linearized reading' (duration: 212.932493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:54:00.992848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.918319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-01T04:54:00.992876Z","caller":"traceutil/trace.go:171","msg":"trace[915995331] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15961; }","duration":"214.9659ms","start":"2026-09-01T04:54:00.777903Z","end":"2026-09-01T04:54:00.992869Z","steps":["trace[915995331] 'agreement among raft nodes before linearized reading' (duration: 214.871058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:54:00.992882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.428837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:54:00.992964Z","caller":"traceutil/trace.go:171","msg":"trace[1506315176] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15961; }","duration":"217.54326ms","start":"2026-09-01T04:54:00.7754Z","end":"2026-09-01T04:54:00.992943Z","steps":["trace[1506315176] 'agreement among raft nodes before linearized reading' (duration: 217.408707ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:54:00.992971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.730068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-01T04:54:00.993016Z","caller":"traceutil/trace.go:171","msg":"trace[2135547397] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15961; }","duration":"203.81406ms","start":"2026-09-01T04:54:00.789186Z","end":"2026-09-01T04:54:00.993Z","steps":["trace[2135547397] 'agreement among raft nodes before linearized reading' (duration: 203.687907ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:54:00.993427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.557585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-01T04:54:00.993507Z","caller":"traceutil/trace.go:171","msg":"trace[570060399] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15961; }","duration":"221.8186ms","start":"2026-09-01T04:54:00.771666Z","end":"2026-09-01T04:54:00.993485Z","steps":["trace[570060399] 'agreement among raft nodes before linearized reading' (duration: 221.268677ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:54:25.276937Z","caller":"traceutil/trace.go:171","msg":"trace[424573479] linearizableReadLoop","detail":"{readStateIndex:16649; appliedIndex:16648; }","duration":"178.254014ms","start":"2026-09-01T04:54:25.098657Z","end":"2026-09-01T04:54:25.276911Z","steps":["trace[424573479] 'read index received' (duration: 178.019309ms)","trace[424573479] 'applied index is now lower than readState.Index' (duration: 233.875µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:54:25.277228Z","caller":"traceutil/trace.go:171","msg":"trace[866721850] transaction","detail":"{read_only:false; response_revision:16092; number_of_response:1; }","duration":"182.035383ms","start":"2026-09-01T04:54:25.095183Z","end":"2026-09-01T04:54:25.277218Z","steps":["trace[866721850] 'process raft request' (duration: 181.566191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:54:25.277416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.731366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:54:25.277441Z","caller":"traceutil/trace.go:171","msg":"trace[774694134] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16092; }","duration":"178.801318ms","start":"2026-09-01T04:54:25.098633Z","end":"2026-09-01T04:54:25.277435Z","steps":["trace[774694134] 'agreement among raft nodes before linearized reading' (duration: 178.734066ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:54:25.367096Z","caller":"traceutil/trace.go:171","msg":"trace[232092624] transaction","detail":"{read_only:false; response_revision:16093; number_of_response:1; }","duration":"267.617607ms","start":"2026-09-01T04:54:25.09946Z","end":"2026-09-01T04:54:25.367077Z","steps":["trace[232092624] 'process raft request' (duration: 267.469454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:54:25.433165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.60371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T04:54:25.433267Z","caller":"traceutil/trace.go:171","msg":"trace[593601272] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:16093; }","duration":"280.763334ms","start":"2026-09-01T04:54:25.152477Z","end":"2026-09-01T04:54:25.43324Z","steps":["trace[593601272] 'agreement among raft nodes before linearized reading' (duration: 214.814147ms)","trace[593601272] 'count revisions from in-memory index tree' (duration: 65.793574ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:54:25.433278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.850685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-01T04:54:25.433297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.644034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:54:25.433354Z","caller":"traceutil/trace.go:171","msg":"trace[1814400400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16093; }","duration":"186.941997ms","start":"2026-09-01T04:54:25.24639Z","end":"2026-09-01T04:54:25.433332Z","steps":["trace[1814400400] 'agreement among raft nodes before linearized reading' (duration: 120.892058ms)","trace[1814400400] 'range keys from in-memory index tree' (duration: 65.864895ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:54:25.433382Z","caller":"traceutil/trace.go:171","msg":"trace[479462543] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16093; }","duration":"284.772257ms","start":"2026-09-01T04:54:25.148585Z","end":"2026-09-01T04:54:25.433357Z","steps":["trace[479462543] 'agreement among raft nodes before linearized reading' (duration: 218.742998ms)","trace[479462543] 'range keys from in-memory index tree' (duration: 65.932267ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:54:25.433485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.009074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:54:25.433507Z","caller":"traceutil/trace.go:171","msg":"trace[489061740] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16093; }","duration":"334.030125ms","start":"2026-09-01T04:54:25.099469Z","end":"2026-09-01T04:54:25.433499Z","steps":["trace[489061740] 'agreement among raft nodes before linearized reading' (duration: 267.833952ms)","trace[489061740] 'range keys from in-memory index tree' (duration: 66.168202ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:54:25.433524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:54:25.099458Z","time spent":"334.061255ms","remote":"127.0.0.1:48132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-09-01T04:54:25.433601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.300102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:54:25.433637Z","caller":"traceutil/trace.go:171","msg":"trace[79827968] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16093; }","duration":"292.346624ms","start":"2026-09-01T04:54:25.14128Z","end":"2026-09-01T04:54:25.433626Z","steps":["trace[79827968] 'agreement among raft nodes before linearized reading' (duration: 226.057699ms)","trace[79827968] 'range keys from in-memory index tree' (duration: 66.245634ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:54:25.433699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.885005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:54:25.433741Z","caller":"traceutil/trace.go:171","msg":"trace[1415265880] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16093; }","duration":"334.940996ms","start":"2026-09-01T04:54:25.098793Z","end":"2026-09-01T04:54:25.433734Z","steps":["trace[1415265880] 'agreement among raft nodes before linearized reading' (duration: 268.516718ms)","trace[1415265880] 'range keys from in-memory index tree' (duration: 66.378036ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:54:25.433759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:54:25.098786Z","time spent":"334.968227ms","remote":"127.0.0.1:48132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-09-01T04:54:25.433739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.913733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-01T04:54:25.433792Z","caller":"traceutil/trace.go:171","msg":"trace[1456457006] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16093; }","duration":"331.004124ms","start":"2026-09-01T04:54:25.102774Z","end":"2026-09-01T04:54:25.433778Z","steps":["trace[1456457006] 'agreement among raft nodes before linearized reading' (duration: 264.524135ms)","trace[1456457006] 'range keys from in-memory index tree' (duration: 66.330406ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:54:25.433818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:54:25.102761Z","time spent":"331.051756ms","remote":"127.0.0.1:48132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5470,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-09-01T04:54:25.433813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.846913ms","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:5"} {"level":"warn","ts":"2026-09-01T04:54:25.433878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.786354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-01T04:54:25.433901Z","caller":"traceutil/trace.go:171","msg":"trace[1039089086] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16093; }","duration":"292.833825ms","start":"2026-09-01T04:54:25.141061Z","end":"2026-09-01T04:54:25.433894Z","steps":["trace[1039089086] 'agreement among raft nodes before linearized reading' (duration: 226.312265ms)","trace[1039089086] 'range keys from in-memory index tree' (duration: 66.401907ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:54:25.433875Z","caller":"traceutil/trace.go:171","msg":"trace[1095086139] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:16093; }","duration":"132.011046ms","start":"2026-09-01T04:54:25.301851Z","end":"2026-09-01T04:54:25.433862Z","steps":["trace[1095086139] 'agreement among raft nodes before linearized reading' (duration: 65.501946ms)","trace[1095086139] 'count revisions from in-memory index tree' (duration: 66.334276ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:54:25.433997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.107791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-01T04:54:25.434026Z","caller":"traceutil/trace.go:171","msg":"trace[1757025343] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16093; }","duration":"326.155082ms","start":"2026-09-01T04:54:25.107861Z","end":"2026-09-01T04:54:25.434016Z","steps":["trace[1757025343] 'agreement among raft nodes before linearized reading' (duration: 259.506968ms)","trace[1757025343] 'range keys from in-memory index tree' (duration: 66.53157ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:54:25.434052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:54:25.107852Z","time spent":"326.192992ms","remote":"127.0.0.1:48132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5470,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-09-01T04:54:25.43441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.403041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-01T04:54:25.434481Z","caller":"traceutil/trace.go:171","msg":"trace[1788165894] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16093; }","duration":"330.500363ms","start":"2026-09-01T04:54:25.10396Z","end":"2026-09-01T04:54:25.43446Z","steps":["trace[1788165894] 'agreement among raft nodes before linearized reading' (duration: 263.341657ms)","trace[1788165894] 'range keys from in-memory index tree' (duration: 65.718481ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:54:25.437603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:54:25.103947Z","time spent":"333.640766ms","remote":"127.0.0.1:48132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5470,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-09-01T04:54:25.437658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.393688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-01T04:54:25.437734Z","caller":"traceutil/trace.go:171","msg":"trace[973207772] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16093; }","duration":"296.491451ms","start":"2026-09-01T04:54:25.14122Z","end":"2026-09-01T04:54:25.437711Z","steps":["trace[973207772] 'agreement among raft nodes before linearized reading' (duration: 226.124291ms)","trace[973207772] 'range keys from in-memory index tree' (duration: 66.338805ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:54:26.458232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.499828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:54:26.458441Z","caller":"traceutil/trace.go:171","msg":"trace[403395075] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:16097; }","duration":"138.765994ms","start":"2026-09-01T04:54:26.319657Z","end":"2026-09-01T04:54:26.458423Z","steps":["trace[403395075] 'count revisions from in-memory index tree' (duration: 138.363725ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:54:26.459003Z","caller":"traceutil/trace.go:171","msg":"trace[331736652] linearizableReadLoop","detail":"{readStateIndex:16656; appliedIndex:16654; }","duration":"110.650239ms","start":"2026-09-01T04:54:26.348339Z","end":"2026-09-01T04:54:26.458989Z","steps":["trace[331736652] 'read index received' (duration: 22.31504ms)","trace[331736652] 'applied index is now lower than readState.Index' (duration: 88.334259ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:54:26.459233Z","caller":"traceutil/trace.go:171","msg":"trace[968544384] transaction","detail":"{read_only:false; response_revision:16098; number_of_response:1; }","duration":"126.831736ms","start":"2026-09-01T04:54:26.332391Z","end":"2026-09-01T04:54:26.459223Z","steps":["trace[968544384] 'process raft request' (duration: 38.338104ms)","trace[968544384] 'compare' (duration: 87.940549ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:54:26.45934Z","caller":"traceutil/trace.go:171","msg":"trace[238720773] transaction","detail":"{read_only:false; response_revision:16099; number_of_response:1; }","duration":"119.522215ms","start":"2026-09-01T04:54:26.339811Z","end":"2026-09-01T04:54:26.459334Z","steps":["trace[238720773] 'process raft request' (duration: 119.144866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:54:26.459493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.16103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-rcx7q\" ","response":"range_response_count:1 size:22017"} {"level":"info","ts":"2026-09-01T04:54:26.45952Z","caller":"traceutil/trace.go:171","msg":"trace[183166263] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-rcx7q; range_end:; response_count:1; response_revision:16099; }","duration":"111.222552ms","start":"2026-09-01T04:54:26.348292Z","end":"2026-09-01T04:54:26.459514Z","steps":["trace[183166263] 'agreement among raft nodes before linearized reading' (duration: 111.12719ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:54:26.662994Z","caller":"traceutil/trace.go:171","msg":"trace[1664233027] transaction","detail":"{read_only:false; response_revision:16100; number_of_response:1; }","duration":"195.411164ms","start":"2026-09-01T04:54:26.46755Z","end":"2026-09-01T04:54:26.662961Z","steps":["trace[1664233027] 'process raft request' (duration: 129.826775ms)","trace[1664233027] 'compare' (duration: 65.432465ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:54:26.66516Z","caller":"traceutil/trace.go:171","msg":"trace[749744721] transaction","detail":"{read_only:false; response_revision:16101; number_of_response:1; }","duration":"197.487423ms","start":"2026-09-01T04:54:26.467639Z","end":"2026-09-01T04:54:26.665127Z","steps":["trace[749744721] 'process raft request' (duration: 197.263357ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:54:26.665033Z","caller":"traceutil/trace.go:171","msg":"trace[35750678] linearizableReadLoop","detail":"{readStateIndex:16658; appliedIndex:16656; }","duration":"109.359099ms","start":"2026-09-01T04:54:26.555656Z","end":"2026-09-01T04:54:26.665015Z","steps":["trace[35750678] 'read index received' (duration: 41.667531ms)","trace[35750678] 'applied index is now lower than readState.Index' (duration: 67.687378ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:54:26.66544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.768018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-01T04:54:26.665498Z","caller":"traceutil/trace.go:171","msg":"trace[752807629] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16101; }","duration":"109.877491ms","start":"2026-09-01T04:54:26.555609Z","end":"2026-09-01T04:54:26.665486Z","steps":["trace[752807629] 'agreement among raft nodes before linearized reading' (duration: 109.628615ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:56:39.329558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14617} {"level":"info","ts":"2026-09-01T04:56:39.417153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14617,"took":"84.481169ms","hash":147869764,"current-db-size-bytes":55205888,"current-db-size":"55 MB","current-db-size-in-use-bytes":31232000,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T04:56:39.417221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":147869764,"revision":14617,"compact-revision":12617} {"level":"info","ts":"2026-09-01T05:00:49.050758Z","caller":"traceutil/trace.go:171","msg":"trace[1126302885] transaction","detail":"{read_only:false; response_revision:18905; number_of_response:1; }","duration":"105.453389ms","start":"2026-09-01T05:00:48.945273Z","end":"2026-09-01T05:00:49.050727Z","steps":["trace[1126302885] 'process raft request' (duration: 57.784377ms)","trace[1126302885] 'compare' (duration: 47.259943ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:00:49.050926Z","caller":"traceutil/trace.go:171","msg":"trace[236390765] linearizableReadLoop","detail":"{readStateIndex:19555; appliedIndex:19554; }","duration":"103.587676ms","start":"2026-09-01T05:00:48.947326Z","end":"2026-09-01T05:00:49.050914Z","steps":["trace[236390765] 'read index received' (duration: 55.743841ms)","trace[236390765] 'applied index is now lower than readState.Index' (duration: 47.842835ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:00:49.05121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.874984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T05:00:49.051257Z","caller":"traceutil/trace.go:171","msg":"trace[495277359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18906; }","duration":"103.990446ms","start":"2026-09-01T05:00:48.947254Z","end":"2026-09-01T05:00:49.051245Z","steps":["trace[495277359] 'agreement among raft nodes before linearized reading' (duration: 103.887913ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:00:49.487235Z","caller":"traceutil/trace.go:171","msg":"trace[72972184] linearizableReadLoop","detail":"{readStateIndex:19558; appliedIndex:19557; }","duration":"237.256265ms","start":"2026-09-01T05:00:49.24995Z","end":"2026-09-01T05:00:49.487206Z","steps":["trace[72972184] 'read index received' (duration: 229.240268ms)","trace[72972184] 'applied index is now lower than readState.Index' (duration: 8.015117ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:00:49.487492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.522291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-24zkr\" ","response":"range_response_count:1 size:20664"} {"level":"info","ts":"2026-09-01T05:00:49.487532Z","caller":"traceutil/trace.go:171","msg":"trace[1094262468] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-24zkr; range_end:; response_count:1; response_revision:18908; }","duration":"237.633913ms","start":"2026-09-01T05:00:49.249887Z","end":"2026-09-01T05:00:49.48752Z","steps":["trace[1094262468] 'agreement among raft nodes before linearized reading' (duration: 237.410988ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:00:49.487662Z","caller":"traceutil/trace.go:171","msg":"trace[1071600807] transaction","detail":"{read_only:false; response_revision:18908; number_of_response:1; }","duration":"299.880725ms","start":"2026-09-01T05:00:49.187733Z","end":"2026-09-01T05:00:49.487614Z","steps":["trace[1071600807] 'process raft request' (duration: 291.477409ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:00:49.487843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:00:49.187693Z","time spent":"300.050649ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:00:49.762964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.798747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T05:00:49.763144Z","caller":"traceutil/trace.go:171","msg":"trace[207472576] transaction","detail":"{read_only:false; response_revision:18909; number_of_response:1; }","duration":"249.07135ms","start":"2026-09-01T05:00:49.514053Z","end":"2026-09-01T05:00:49.763124Z","steps":["trace[207472576] 'process raft request' (duration: 73.982146ms)","trace[207472576] 'compare' (duration: 174.650984ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:00:50.537174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.880565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T05:00:50.537496Z","caller":"traceutil/trace.go:171","msg":"trace[1751039762] transaction","detail":"{read_only:false; response_revision:18913; number_of_response:1; }","duration":"506.827412ms","start":"2026-09-01T05:00:50.030643Z","end":"2026-09-01T05:00:50.53747Z","steps":["trace[1751039762] 'process raft request' (duration: 506.759851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:00:50.537643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:00:50.030633Z","time spent":"506.919864ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:00:50.537932Z","caller":"traceutil/trace.go:171","msg":"trace[476662651] linearizableReadLoop","detail":"{readStateIndex:19561; appliedIndex:19560; }","duration":"691.187872ms","start":"2026-09-01T05:00:49.846714Z","end":"2026-09-01T05:00:50.537902Z","steps":["trace[476662651] 'read index received' (duration: 375.55489ms)","trace[476662651] 'applied index is now lower than readState.Index' (duration: 315.628402ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:00:50.537982Z","caller":"traceutil/trace.go:171","msg":"trace[78414306] transaction","detail":"{read_only:false; response_revision:18912; number_of_response:1; }","duration":"507.995329ms","start":"2026-09-01T05:00:50.029937Z","end":"2026-09-01T05:00:50.537932Z","steps":["trace[78414306] 'process raft request' (duration: 507.386365ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:00:50.538062Z","caller":"traceutil/trace.go:171","msg":"trace[662093881] transaction","detail":"{read_only:false; response_revision:18911; number_of_response:1; }","duration":"755.365139ms","start":"2026-09-01T05:00:49.782629Z","end":"2026-09-01T05:00:50.537994Z","steps":["trace[662093881] 'process raft request' (duration: 439.537363ms)","trace[662093881] 'compare' (duration: 314.411833ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:00:50.538119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.09023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T05:00:50.538185Z","caller":"traceutil/trace.go:171","msg":"trace[1712950357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18913; }","duration":"589.203674ms","start":"2026-09-01T05:00:49.948959Z","end":"2026-09-01T05:00:50.538163Z","steps":["trace[1712950357] 'agreement among raft nodes before linearized reading' (duration: 589.10098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:00:50.538185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:00:50.029888Z","time spent":"508.181723ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:00:50.538203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.480809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29803980-n7f5h\" ","response":"range_response_count:1 size:6959"} {"level":"warn","ts":"2026-09-01T05:00:50.538228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:00:49.948917Z","time spent":"589.301276ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T05:00:50.53825Z","caller":"traceutil/trace.go:171","msg":"trace[517294066] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29803980-n7f5h; range_end:; response_count:1; response_revision:18913; }","duration":"691.577611ms","start":"2026-09-01T05:00:49.846657Z","end":"2026-09-01T05:00:50.538234Z","steps":["trace[517294066] 'agreement among raft nodes before linearized reading' (duration: 691.349745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:00:50.538236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.781427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T05:00:50.538289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:00:49.782603Z","time spent":"755.557575ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:00:50.538294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.07223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-01T05:00:50.538324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:00:49.846632Z","time spent":"691.680604ms","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":6981,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-29803980-n7f5h\" "} {"level":"info","ts":"2026-09-01T05:00:50.538349Z","caller":"traceutil/trace.go:171","msg":"trace[963561268] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:18913; }","duration":"291.211133ms","start":"2026-09-01T05:00:50.247122Z","end":"2026-09-01T05:00:50.538334Z","steps":["trace[963561268] 'agreement among raft nodes before linearized reading' (duration: 290.947706ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:00:50.538344Z","caller":"traceutil/trace.go:171","msg":"trace[228951211] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:0; response_revision:18913; }","duration":"225.90858ms","start":"2026-09-01T05:00:50.31241Z","end":"2026-09-01T05:00:50.538319Z","steps":["trace[228951211] 'agreement among raft nodes before linearized reading' (duration: 225.758917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:00:50.538406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.340513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-01T05:00:50.538374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.706507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-rcx7q\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-09-01T05:00:50.538437Z","caller":"traceutil/trace.go:171","msg":"trace[253868269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18913; }","duration":"236.422635ms","start":"2026-09-01T05:00:50.302003Z","end":"2026-09-01T05:00:50.538425Z","steps":["trace[253868269] 'agreement among raft nodes before linearized reading' (duration: 236.332073ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:00:50.538477Z","caller":"traceutil/trace.go:171","msg":"trace[1899360052] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-rcx7q; range_end:; response_count:1; response_revision:18913; }","duration":"463.868631ms","start":"2026-09-01T05:00:50.074576Z","end":"2026-09-01T05:00:50.538445Z","steps":["trace[1899360052] 'agreement among raft nodes before linearized reading' (duration: 463.524112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:00:50.538294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.426587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-01T05:00:50.53852Z","caller":"traceutil/trace.go:171","msg":"trace[82794833] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18913; }","duration":"441.697044ms","start":"2026-09-01T05:00:50.096813Z","end":"2026-09-01T05:00:50.53851Z","steps":["trace[82794833] 'agreement among raft nodes before linearized reading' (duration: 441.281163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:00:50.538525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:00:50.07455Z","time spent":"463.965743ms","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22040,"request content":"key:\"/registry/pods/openstack/nova-compute-default-rcx7q\" "} {"level":"warn","ts":"2026-09-01T05:00:50.53854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:00:50.096787Z","time spent":"441.746644ms","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-01T05:00:50.695026Z","caller":"traceutil/trace.go:171","msg":"trace[1435436571] transaction","detail":"{read_only:false; response_revision:18915; number_of_response:1; }","duration":"117.359648ms","start":"2026-09-01T05:00:50.577628Z","end":"2026-09-01T05:00:50.694988Z","steps":["trace[1435436571] 'process raft request' (duration: 105.061571ms)","trace[1435436571] 'compare' (duration: 11.836235ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:00:59.211251Z","caller":"traceutil/trace.go:171","msg":"trace[1374573112] linearizableReadLoop","detail":"{readStateIndex:19720; appliedIndex:19719; }","duration":"103.186147ms","start":"2026-09-01T05:00:59.108033Z","end":"2026-09-01T05:00:59.21122Z","steps":["trace[1374573112] 'read index received' (duration: 101.688032ms)","trace[1374573112] 'applied index is now lower than readState.Index' (duration: 1.497105ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:00:59.2114Z","caller":"traceutil/trace.go:171","msg":"trace[575875696] transaction","detail":"{read_only:false; response_revision:19066; number_of_response:1; }","duration":"103.456743ms","start":"2026-09-01T05:00:59.10791Z","end":"2026-09-01T05:00:59.211367Z","steps":["trace[575875696] 'process raft request' (duration: 101.795485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:00:59.211787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.727049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/libvirt-libvirt-default-24zkr-api\" ","response":"range_response_count:1 size:2048"} {"level":"info","ts":"2026-09-01T05:00:59.211911Z","caller":"traceutil/trace.go:171","msg":"trace[751759873] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/libvirt-libvirt-default-24zkr-api; range_end:; response_count:1; response_revision:19066; }","duration":"103.894433ms","start":"2026-09-01T05:00:59.107982Z","end":"2026-09-01T05:00:59.211876Z","steps":["trace[751759873] 'agreement among raft nodes before linearized reading' (duration: 103.612577ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:00:59.218804Z","caller":"traceutil/trace.go:171","msg":"trace[240872453] transaction","detail":"{read_only:false; response_revision:19067; number_of_response:1; }","duration":"105.205774ms","start":"2026-09-01T05:00:59.113562Z","end":"2026-09-01T05:00:59.218767Z","steps":["trace[240872453] 'process raft request' (duration: 104.885566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:00:59.218997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.607163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T05:00:59.219069Z","caller":"traceutil/trace.go:171","msg":"trace[1468905600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19068; }","duration":"105.697016ms","start":"2026-09-01T05:00:59.113356Z","end":"2026-09-01T05:00:59.219053Z","steps":["trace[1468905600] 'agreement among raft nodes before linearized reading' (duration: 105.539592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:00:59.219232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.672615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3959"} {"level":"info","ts":"2026-09-01T05:00:59.219329Z","caller":"traceutil/trace.go:171","msg":"trace[1382597129] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:19068; }","duration":"108.810108ms","start":"2026-09-01T05:00:59.110496Z","end":"2026-09-01T05:00:59.219306Z","steps":["trace[1382597129] 'agreement among raft nodes before linearized reading' (duration: 108.48805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:00:59.219363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.131999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-09-01T05:00:59.219401Z","caller":"traceutil/trace.go:171","msg":"trace[1909934174] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:19068; }","duration":"110.17349ms","start":"2026-09-01T05:00:59.109217Z","end":"2026-09-01T05:00:59.21939Z","steps":["trace[1909934174] 'agreement among raft nodes before linearized reading' (duration: 109.627156ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:01:25.821632Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"373d5d49ce4adaff","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T05:01:25.83444Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-01T05:01:25.834599Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-01T05:01:39.337029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16994} {"level":"info","ts":"2026-09-01T05:01:39.44851Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16994,"took":"108.313446ms","hash":1251464221,"current-db-size-bytes":55205888,"current-db-size":"55 MB","current-db-size-in-use-bytes":33488896,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-01T05:01:39.448569Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1251464221,"revision":16994,"compact-revision":14617} {"level":"warn","ts":"2026-09-01T05:02:06.413312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.148873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-01T05:02:06.413433Z","caller":"traceutil/trace.go:171","msg":"trace[1482472408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19740; }","duration":"150.360268ms","start":"2026-09-01T05:02:06.263047Z","end":"2026-09-01T05:02:06.413407Z","steps":["trace[1482472408] 'range keys from in-memory index tree' (duration: 149.907478ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:02:36.905321Z","caller":"traceutil/trace.go:171","msg":"trace[1165523028] transaction","detail":"{read_only:false; response_revision:19956; number_of_response:1; }","duration":"220.313278ms","start":"2026-09-01T05:02:36.684974Z","end":"2026-09-01T05:02:36.905287Z","steps":["trace[1165523028] 'process raft request' (duration: 219.653463ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:06:39.346304Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19522} {"level":"info","ts":"2026-09-01T05:06:39.48722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19522,"took":"136.38089ms","hash":3811591364,"current-db-size-bytes":55205888,"current-db-size":"55 MB","current-db-size-in-use-bytes":30277632,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T05:06:39.487319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3811591364,"revision":19522,"compact-revision":16994} {"level":"info","ts":"2026-09-01T05:06:53.694962Z","caller":"traceutil/trace.go:171","msg":"trace[1745936240] transaction","detail":"{read_only:false; response_revision:21732; number_of_response:1; }","duration":"279.470018ms","start":"2026-09-01T05:06:53.415403Z","end":"2026-09-01T05:06:53.694873Z","steps":["trace[1745936240] 'process raft request' (duration: 225.049698ms)","trace[1745936240] 'compare' (duration: 54.094142ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:06:54.208297Z","caller":"traceutil/trace.go:171","msg":"trace[623727667] transaction","detail":"{read_only:false; response_revision:21733; number_of_response:1; }","duration":"143.6145ms","start":"2026-09-01T05:06:54.064633Z","end":"2026-09-01T05:06:54.208247Z","steps":["trace[623727667] 'process raft request' (duration: 143.349174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:06:54.518029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.488246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T05:06:54.518195Z","caller":"traceutil/trace.go:171","msg":"trace[1182152606] linearizableReadLoop","detail":"{readStateIndex:22476; appliedIndex:22474; }","duration":"442.762046ms","start":"2026-09-01T05:06:54.075417Z","end":"2026-09-01T05:06:54.518179Z","steps":["trace[1182152606] 'read index received' (duration: 132.049999ms)","trace[1182152606] 'applied index is now lower than readState.Index' (duration: 310.710807ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:06:54.518332Z","caller":"traceutil/trace.go:171","msg":"trace[1560367027] transaction","detail":"{read_only:false; response_revision:21734; number_of_response:1; }","duration":"451.829617ms","start":"2026-09-01T05:06:54.066491Z","end":"2026-09-01T05:06:54.518321Z","steps":["trace[1560367027] 'process raft request' (duration: 301.922371ms)","trace[1560367027] 'compare' (duration: 149.341663ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:06:54.518392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:06:54.066457Z","time spent":"451.89952ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:06:54.518734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.32173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T05:06:54.51877Z","caller":"traceutil/trace.go:171","msg":"trace[1105543672] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:21735; }","duration":"443.391681ms","start":"2026-09-01T05:06:54.075362Z","end":"2026-09-01T05:06:54.518753Z","steps":["trace[1105543672] 'agreement among raft nodes before linearized reading' (duration: 443.313879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:06:54.518797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:06:54.075347Z","time spent":"443.442182ms","remote":"127.0.0.1:48308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-09-01T05:06:54.874232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.552781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T05:06:54.874381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.339208ms","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-09-01T05:06:54.874401Z","caller":"traceutil/trace.go:171","msg":"trace[58392125] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21736; }","duration":"231.767797ms","start":"2026-09-01T05:06:54.642603Z","end":"2026-09-01T05:06:54.874371Z","steps":["trace[58392125] 'range keys from in-memory index tree' (duration: 231.379947ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:06:54.874447Z","caller":"traceutil/trace.go:171","msg":"trace[273450779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21736; }","duration":"250.424601ms","start":"2026-09-01T05:06:54.624004Z","end":"2026-09-01T05:06:54.874429Z","steps":["trace[273450779] 'range keys from in-memory index tree' (duration: 250.101103ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:06:54.874632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.495872ms","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-09-01T05:06:54.874699Z","caller":"traceutil/trace.go:171","msg":"trace[81934411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21736; }","duration":"256.615795ms","start":"2026-09-01T05:06:54.618067Z","end":"2026-09-01T05:06:54.874683Z","steps":["trace[81934411] 'range keys from in-memory index tree' (duration: 256.341438ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:06:54.920369Z","caller":"traceutil/trace.go:171","msg":"trace[1494679561] transaction","detail":"{read_only:false; response_revision:21737; number_of_response:1; }","duration":"172.233297ms","start":"2026-09-01T05:06:54.748061Z","end":"2026-09-01T05:06:54.920295Z","steps":["trace[1494679561] 'process raft request' (duration: 171.758005ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:06:55.244501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.890319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T05:06:55.244745Z","caller":"traceutil/trace.go:171","msg":"trace[1316535087] linearizableReadLoop","detail":"{readStateIndex:22481; appliedIndex:22479; }","duration":"318.970849ms","start":"2026-09-01T05:06:54.925749Z","end":"2026-09-01T05:06:55.244719Z","steps":["trace[1316535087] 'read index received' (duration: 128.1984ms)","trace[1316535087] 'applied index is now lower than readState.Index' (duration: 190.770839ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:06:55.244889Z","caller":"traceutil/trace.go:171","msg":"trace[2108874598] transaction","detail":"{read_only:false; response_revision:21738; number_of_response:1; }","duration":"364.426269ms","start":"2026-09-01T05:06:54.880446Z","end":"2026-09-01T05:06:55.244872Z","steps":["trace[2108874598] 'process raft request' (duration: 173.592888ms)","trace[2108874598] 'compare' (duration: 189.692785ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:06:55.244967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:06:54.880424Z","time spent":"364.502821ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:06:55.24515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.993137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T05:06:55.245217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.455901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-01T05:06:55.245176Z","caller":"traceutil/trace.go:171","msg":"trace[57471692] transaction","detail":"{read_only:false; response_revision:21739; number_of_response:1; }","duration":"363.11784ms","start":"2026-09-01T05:06:54.881963Z","end":"2026-09-01T05:06:55.245081Z","steps":["trace[57471692] 'process raft request' (duration: 362.673229ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:06:55.245282Z","caller":"traceutil/trace.go:171","msg":"trace[926730531] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21739; }","duration":"319.563834ms","start":"2026-09-01T05:06:54.925697Z","end":"2026-09-01T05:06:55.245261Z","steps":["trace[926730531] 'agreement among raft nodes before linearized reading' (duration: 319.269386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:06:55.245363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:06:54.925675Z","time spent":"319.672787ms","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-01T05:06:55.245285Z","caller":"traceutil/trace.go:171","msg":"trace[560223162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21739; }","duration":"294.198322ms","start":"2026-09-01T05:06:54.951056Z","end":"2026-09-01T05:06:55.245255Z","steps":["trace[560223162] 'agreement among raft nodes before linearized reading' (duration: 293.940295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:06:55.245489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:06:54.881934Z","time spent":"363.409967ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:06:55.557734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.016855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-01T05:06:55.557866Z","caller":"traceutil/trace.go:171","msg":"trace[71940224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21739; }","duration":"143.196039ms","start":"2026-09-01T05:06:55.414607Z","end":"2026-09-01T05:06:55.557803Z","steps":["trace[71940224] 'range keys from in-memory index tree' (duration: 142.76551ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:11:13.386295Z","caller":"traceutil/trace.go:171","msg":"trace[1396439661] linearizableReadLoop","detail":"{readStateIndex:25152; appliedIndex:25151; }","duration":"104.682472ms","start":"2026-09-01T05:11:13.281541Z","end":"2026-09-01T05:11:13.386224Z","steps":["trace[1396439661] 'read index received' (duration: 104.400416ms)","trace[1396439661] 'applied index is now lower than readState.Index' (duration: 280.746µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:11:13.386363Z","caller":"traceutil/trace.go:171","msg":"trace[1819911443] transaction","detail":"{read_only:false; response_revision:24336; number_of_response:1; }","duration":"245.063085ms","start":"2026-09-01T05:11:13.141254Z","end":"2026-09-01T05:11:13.386317Z","steps":["trace[1819911443] 'process raft request' (duration: 244.692887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:11:13.386731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.128252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-01T05:11:13.386874Z","caller":"traceutil/trace.go:171","msg":"trace[195743556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24336; }","duration":"105.366808ms","start":"2026-09-01T05:11:13.281456Z","end":"2026-09-01T05:11:13.386822Z","steps":["trace[195743556] 'agreement among raft nodes before linearized reading' (duration: 104.930258ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:11:13.453718Z","caller":"traceutil/trace.go:171","msg":"trace[1230330783] transaction","detail":"{read_only:false; response_revision:24337; number_of_response:1; }","duration":"146.857525ms","start":"2026-09-01T05:11:13.30681Z","end":"2026-09-01T05:11:13.453667Z","steps":["trace[1230330783] 'process raft request' (duration: 146.65463ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:11:39.374232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21661} {"level":"info","ts":"2026-09-01T05:11:39.645937Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21661,"took":"266.28743ms","hash":2485486578,"current-db-size-bytes":55205888,"current-db-size":"55 MB","current-db-size-in-use-bytes":42545152,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-01T05:11:39.646038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2485486578,"revision":21661,"compact-revision":19522} {"level":"info","ts":"2026-09-01T05:15:52.600477Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006bdc.wal"} {"level":"info","ts":"2026-09-01T05:16:39.386535Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24618} {"level":"info","ts":"2026-09-01T05:16:39.556313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24618,"took":"163.198047ms","hash":1023445847,"current-db-size-bytes":56479744,"current-db-size":"56 MB","current-db-size-in-use-bytes":33615872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-01T05:16:39.556392Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1023445847,"revision":24618,"compact-revision":21661} {"level":"info","ts":"2026-09-01T05:21:05.011659Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"373d5d49ce4adaff","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T05:21:05.01931Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-01T05:21:05.019426Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-01T05:21:39.396565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27079} {"level":"info","ts":"2026-09-01T05:21:39.545023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27079,"took":"142.489773ms","hash":1350586829,"current-db-size-bytes":56479744,"current-db-size":"56 MB","current-db-size-in-use-bytes":30785536,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T05:21:39.545103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1350586829,"revision":27079,"compact-revision":24618}