{"level":"warn","ts":"2026-08-22T10:33:33.02058Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:33:33.020719Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.157: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://199.19.213.157:2380","--initial-cluster=instance=https://199.19.213.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.157: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-08-22T10:33:33.020847Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:33:33.020866Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.157:2380"]} {"level":"info","ts":"2026-08-22T10:33:33.020893Z","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-08-22T10:33:33.022075Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"]} {"level":"info","ts":"2026-08-22T10:33:33.022201Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.157:2380"],"listen-peer-urls":["https://199.19.213.157:2380"],"advertise-client-urls":["https://199.19.213.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.157: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-08-22T10:33:33.025981Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.399755ms"} {"level":"info","ts":"2026-08-22T10:33:33.032643Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"10058a425931b8de","cluster-id":"2ff97ff1d0272754"} {"level":"info","ts":"2026-08-22T10:33:33.032721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T10:33:33.032784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became follower at term 0"} {"level":"info","ts":"2026-08-22T10:33:33.032805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 10058a425931b8de [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T10:33:33.032816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became follower at term 1"} {"level":"info","ts":"2026-08-22T10:33:33.032859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=(1154480897059305694)"} {"level":"warn","ts":"2026-08-22T10:33:33.03955Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T10:33:33.041118Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T10:33:33.04214Z","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-08-22T10:33:33.044458Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"10058a425931b8de","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T10:33:33.044591Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"10058a425931b8de","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T10:33:33.04473Z","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-08-22T10:33:33.044794Z","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-08-22T10:33:33.044806Z","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-08-22T10:33:33.045872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=(1154480897059305694)"} {"level":"info","ts":"2026-08-22T10:33:33.046006Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ff97ff1d0272754","local-member-id":"10058a425931b8de","added-peer-id":"10058a425931b8de","added-peer-peer-urls":["https://199.19.213.157:2380"]} {"level":"info","ts":"2026-08-22T10:33:33.047801Z","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-08-22T10:33:33.048012Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"10058a425931b8de","initial-advertise-peer-urls":["https://199.19.213.157:2380"],"listen-peer-urls":["https://199.19.213.157:2380"],"advertise-client-urls":["https://199.19.213.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T10:33:33.048047Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T10:33:33.048962Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.157:2380"} {"level":"info","ts":"2026-08-22T10:33:33.049016Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.157:2380"} {"level":"info","ts":"2026-08-22T10:33:33.538097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T10:33:33.538141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T10:33:33.538166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de received MsgPreVoteResp from 10058a425931b8de at term 1"} {"level":"info","ts":"2026-08-22T10:33:33.538181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became candidate at term 2"} {"level":"info","ts":"2026-08-22T10:33:33.538186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de received MsgVoteResp from 10058a425931b8de at term 2"} {"level":"info","ts":"2026-08-22T10:33:33.538195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became leader at term 2"} {"level":"info","ts":"2026-08-22T10:33:33.538202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 10058a425931b8de elected leader 10058a425931b8de at term 2"} {"level":"info","ts":"2026-08-22T10:33:33.539734Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:33:33.540731Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:33:33.540837Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:33:33.54112Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ff97ff1d0272754","local-member-id":"10058a425931b8de","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:33:33.541217Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:33:33.541248Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:33:33.540689Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"10058a425931b8de","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.157:2379]}","request-path":"/0/members/10058a425931b8de/attributes","cluster-id":"2ff97ff1d0272754","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T10:33:33.542172Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T10:33:33.542255Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T10:33:33.5429Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T10:33:33.543224Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.157:2379"} {"level":"info","ts":"2026-08-22T10:33:37.077105Z","caller":"traceutil/trace.go:171","msg":"trace[32704564] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"233.946612ms","start":"2026-08-22T10:33:36.843135Z","end":"2026-08-22T10:33:37.077082Z","steps":["trace[32704564] 'process raft request' (duration: 233.803068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:37.37032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.199145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:33:37.370592Z","caller":"traceutil/trace.go:171","msg":"trace[87766996] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"288.875728ms","start":"2026-08-22T10:33:37.081617Z","end":"2026-08-22T10:33:37.370492Z","steps":["trace[87766996] 'process raft request' (duration: 76.923406ms)","trace[87766996] 'compare' (duration: 211.067988ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:33:47.531101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260845903932285,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:33:47.907362Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.444337494s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T10:33:47.907724Z","caller":"traceutil/trace.go:171","msg":"trace[1956594293] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"876.999367ms","start":"2026-08-22T10:33:47.030698Z","end":"2026-08-22T10:33:47.907698Z","steps":["trace[1956594293] 'read index received' (duration: 876.849237ms)","trace[1956594293] 'applied index is now lower than readState.Index' (duration: 148.541µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:33:47.907749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:46.46293Z","time spent":"1.444811571s","remote":"127.0.0.1:43466","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-22T10:33:47.907879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.163105ms","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-08-22T10:33:47.907921Z","caller":"traceutil/trace.go:171","msg":"trace[882052896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:398; }","duration":"877.260495ms","start":"2026-08-22T10:33:47.030649Z","end":"2026-08-22T10:33:47.90791Z","steps":["trace[882052896] 'agreement among raft nodes before linearized reading' (duration: 877.155226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:47.907962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:47.03063Z","time spent":"877.319344ms","remote":"127.0.0.1:43686","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-08-22T10:33:47.907964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.235296ms","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-08-22T10:33:47.907958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.737189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-22T10:33:47.908004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.929931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T10:33:47.908046Z","caller":"traceutil/trace.go:171","msg":"trace[1832658214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:398; }","duration":"608.36338ms","start":"2026-08-22T10:33:47.299645Z","end":"2026-08-22T10:33:47.908009Z","steps":["trace[1832658214] 'agreement among raft nodes before linearized reading' (duration: 608.242984ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:47.908044Z","caller":"traceutil/trace.go:171","msg":"trace[410323837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:398; }","duration":"876.871153ms","start":"2026-08-22T10:33:47.031158Z","end":"2026-08-22T10:33:47.908029Z","steps":["trace[410323837] 'agreement among raft nodes before linearized reading' (duration: 876.699677ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:47.908054Z","caller":"traceutil/trace.go:171","msg":"trace[1703555060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:398; }","duration":"837.028152ms","start":"2026-08-22T10:33:47.071007Z","end":"2026-08-22T10:33:47.908035Z","steps":["trace[1703555060] 'agreement among raft nodes before linearized reading' (duration: 836.916834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:47.908085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:47.299626Z","time spent":"608.450443ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T10:33:47.908087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:47.031145Z","time spent":"876.93319ms","remote":"127.0.0.1:43686","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":"warn","ts":"2026-08-22T10:33:47.908091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:47.070986Z","time spent":"837.095728ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T10:34:10.477793Z","caller":"traceutil/trace.go:171","msg":"trace[158297827] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"213.842698ms","start":"2026-08-22T10:34:10.263928Z","end":"2026-08-22T10:34:10.47777Z","steps":["trace[158297827] 'process raft request' (duration: 213.119003ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:10.482651Z","caller":"traceutil/trace.go:171","msg":"trace[1271325717] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:642; }","duration":"100.329188ms","start":"2026-08-22T10:34:10.38226Z","end":"2026-08-22T10:34:10.482589Z","steps":["trace[1271325717] 'read index received' (duration: 94.27571ms)","trace[1271325717] 'applied index is now lower than readState.Index' (duration: 6.051878ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:34:10.482883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.608334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:34:10.482925Z","caller":"traceutil/trace.go:171","msg":"trace[966710169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"100.697014ms","start":"2026-08-22T10:34:10.382216Z","end":"2026-08-22T10:34:10.482913Z","steps":["trace[966710169] 'agreement among raft nodes before linearized reading' (duration: 100.510097ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:10.483303Z","caller":"traceutil/trace.go:171","msg":"trace[1486241404] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"152.543318ms","start":"2026-08-22T10:34:10.33074Z","end":"2026-08-22T10:34:10.483283Z","steps":["trace[1486241404] 'process raft request' (duration: 151.5678ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:10.483481Z","caller":"traceutil/trace.go:171","msg":"trace[970284847] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"113.083658ms","start":"2026-08-22T10:34:10.370379Z","end":"2026-08-22T10:34:10.483463Z","steps":["trace[970284847] 'process raft request' (duration: 112.158378ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:17.317659Z","caller":"traceutil/trace.go:171","msg":"trace[1995107019] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"345.684878ms","start":"2026-08-22T10:34:16.971947Z","end":"2026-08-22T10:34:17.317632Z","steps":["trace[1995107019] 'process raft request' (duration: 345.453751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:17.318254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:16.971919Z","time spent":"345.881242ms","remote":"127.0.0.1:43612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:34:17.86413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.668205ms","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-08-22T10:34:17.864205Z","caller":"traceutil/trace.go:171","msg":"trace[552824669] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"215.790197ms","start":"2026-08-22T10:34:17.648397Z","end":"2026-08-22T10:34:17.864188Z","steps":["trace[552824669] 'range keys from in-memory index tree' (duration: 215.51772ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:50.562145Z","caller":"traceutil/trace.go:171","msg":"trace[1449516487] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"226.162065ms","start":"2026-08-22T10:35:50.33595Z","end":"2026-08-22T10:35:50.562112Z","steps":["trace[1449516487] 'process raft request' (duration: 225.954723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:50.562489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.933968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:35:50.562563Z","caller":"traceutil/trace.go:171","msg":"trace[1741265595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1878; }","duration":"179.076001ms","start":"2026-08-22T10:35:50.383473Z","end":"2026-08-22T10:35:50.562549Z","steps":["trace[1741265595] 'agreement among raft nodes before linearized reading' (duration: 178.889701ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:50.563044Z","caller":"traceutil/trace.go:171","msg":"trace[449980169] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1936; }","duration":"178.688119ms","start":"2026-08-22T10:35:50.38354Z","end":"2026-08-22T10:35:50.562228Z","steps":["trace[449980169] 'read index received' (duration: 178.678518ms)","trace[449980169] 'applied index is now lower than readState.Index' (duration: 8.041µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:50.563375Z","caller":"traceutil/trace.go:171","msg":"trace[689476530] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"178.999609ms","start":"2026-08-22T10:35:50.38436Z","end":"2026-08-22T10:35:50.56336Z","steps":["trace[689476530] 'process raft request' (duration: 178.918716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:51.543488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.09228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:35:51.543581Z","caller":"traceutil/trace.go:171","msg":"trace[1879773624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1889; }","duration":"159.209139ms","start":"2026-08-22T10:35:51.384332Z","end":"2026-08-22T10:35:51.543541Z","steps":["trace[1879773624] 'range keys from in-memory index tree' (duration: 159.011238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:51.543717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.796187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-08-22T10:35:51.543819Z","caller":"traceutil/trace.go:171","msg":"trace[449720953] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1889; }","duration":"137.924388ms","start":"2026-08-22T10:35:51.405854Z","end":"2026-08-22T10:35:51.543778Z","steps":["trace[449720953] 'range keys from in-memory index tree' (duration: 137.653806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:51.543958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.996357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1576fac7-47f7-4c20-b06c-3044260290b7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-22T10:35:51.544041Z","caller":"traceutil/trace.go:171","msg":"trace[1479829791] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1576fac7-47f7-4c20-b06c-3044260290b7; range_end:; response_count:1; response_revision:1889; }","duration":"124.228752ms","start":"2026-08-22T10:35:51.419796Z","end":"2026-08-22T10:35:51.544025Z","steps":["trace[1479829791] 'range keys from in-memory index tree' (duration: 123.692521ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:51.681955Z","caller":"traceutil/trace.go:171","msg":"trace[1340249997] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"130.343593ms","start":"2026-08-22T10:35:51.551586Z","end":"2026-08-22T10:35:51.68193Z","steps":["trace[1340249997] 'process raft request' (duration: 130.222735ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:51.799478Z","caller":"traceutil/trace.go:171","msg":"trace[177697551] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"247.396383ms","start":"2026-08-22T10:35:51.552034Z","end":"2026-08-22T10:35:51.799431Z","steps":["trace[177697551] 'process raft request' (duration: 205.85762ms)","trace[177697551] 'compare' (duration: 41.365827ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:51.799618Z","caller":"traceutil/trace.go:171","msg":"trace[99288413] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1948; }","duration":"229.058311ms","start":"2026-08-22T10:35:51.570469Z","end":"2026-08-22T10:35:51.799527Z","steps":["trace[99288413] 'read index received' (duration: 111.956564ms)","trace[99288413] 'applied index is now lower than readState.Index' (duration: 117.098586ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:35:51.850427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.937437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1576fac7-47f7-4c20-b06c-3044260290b7\" ","response":"range_response_count:1 size:3744"} {"level":"warn","ts":"2026-08-22T10:35:51.850495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.202465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-22T10:35:51.850515Z","caller":"traceutil/trace.go:171","msg":"trace[1048249543] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1576fac7-47f7-4c20-b06c-3044260290b7; range_end:; response_count:1; response_revision:1891; }","duration":"280.059696ms","start":"2026-08-22T10:35:51.570435Z","end":"2026-08-22T10:35:51.850495Z","steps":["trace[1048249543] 'agreement among raft nodes before linearized reading' (duration: 229.202343ms)","trace[1048249543] 'range keys from in-memory index tree' (duration: 50.666204ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:51.850544Z","caller":"traceutil/trace.go:171","msg":"trace[88118839] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1891; }","duration":"189.290168ms","start":"2026-08-22T10:35:51.661239Z","end":"2026-08-22T10:35:51.850529Z","steps":["trace[88118839] 'agreement among raft nodes before linearized reading' (duration: 189.111601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:00.560761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260845903935388,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:36:01.061309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260845903935388,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:36:01.225405Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.297090233s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T10:36:01.225823Z","caller":"traceutil/trace.go:171","msg":"trace[273873306] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"1.297555164s","start":"2026-08-22T10:35:59.928225Z","end":"2026-08-22T10:36:01.22578Z","steps":["trace[273873306] 'process raft request' (duration: 1.29742412s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:01.225944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.928212Z","time spent":"1.297657644s","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:36:01.226441Z","caller":"traceutil/trace.go:171","msg":"trace[1225017247] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2048; }","duration":"1.166099887s","start":"2026-08-22T10:36:00.06033Z","end":"2026-08-22T10:36:01.22643Z","steps":["trace[1225017247] 'read index received' (duration: 1.165312024s)","trace[1225017247] 'applied index is now lower than readState.Index' (duration: 787.173µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:36:01.226489Z","caller":"traceutil/trace.go:171","msg":"trace[375091986] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"634.604493ms","start":"2026-08-22T10:36:00.591876Z","end":"2026-08-22T10:36:01.22648Z","steps":["trace[375091986] 'process raft request' (duration: 634.48055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:01.226521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.59185Z","time spent":"634.649608ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:36:01.226593Z","caller":"traceutil/trace.go:171","msg":"trace[1890997143] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"633.466787ms","start":"2026-08-22T10:36:00.593121Z","end":"2026-08-22T10:36:01.226588Z","steps":["trace[1890997143] 'process raft request' (duration: 633.286908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:01.226622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.593101Z","time spent":"633.505271ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:36:01.22673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166415971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-22T10:36:01.226755Z","caller":"traceutil/trace.go:171","msg":"trace[644827991] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1989; }","duration":"1.166504941s","start":"2026-08-22T10:36:00.060244Z","end":"2026-08-22T10:36:01.226749Z","steps":["trace[644827991] 'agreement among raft nodes before linearized reading' (duration: 1.166421072s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:01.22677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.060214Z","time spent":"1.166553126s","remote":"127.0.0.1:38116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4613,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-22T10:36:01.226993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.624687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-08-22T10:36:01.227001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.130413ms","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-08-22T10:36:01.227021Z","caller":"traceutil/trace.go:171","msg":"trace[1138553759] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1989; }","duration":"157.690394ms","start":"2026-08-22T10:36:01.069322Z","end":"2026-08-22T10:36:01.227013Z","steps":["trace[1138553759] 'agreement among raft nodes before linearized reading' (duration: 157.613616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:01.227015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.69309ms","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":"warn","ts":"2026-08-22T10:36:01.227026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.957534ms","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-08-22T10:36:01.227065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.084717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-22T10:36:01.227082Z","caller":"traceutil/trace.go:171","msg":"trace[2009868164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1989; }","duration":"580.123372ms","start":"2026-08-22T10:36:00.646955Z","end":"2026-08-22T10:36:01.227078Z","steps":["trace[2009868164] 'agreement among raft nodes before linearized reading' (duration: 580.079337ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:01.22708Z","caller":"traceutil/trace.go:171","msg":"trace[2057053568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1989; }","duration":"580.064566ms","start":"2026-08-22T10:36:00.647003Z","end":"2026-08-22T10:36:01.227068Z","steps":["trace[2057053568] 'agreement among raft nodes before linearized reading' (duration: 579.925911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:01.227094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.646942Z","time spent":"580.149804ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T10:36:01.227024Z","caller":"traceutil/trace.go:171","msg":"trace[1095541148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1989; }","duration":"580.174228ms","start":"2026-08-22T10:36:00.646845Z","end":"2026-08-22T10:36:01.227019Z","steps":["trace[1095541148] 'agreement among raft nodes before linearized reading' (duration: 580.112341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:01.227108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.712471ms","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-08-22T10:36:01.227116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.646987Z","time spent":"580.118951ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T10:36:01.227128Z","caller":"traceutil/trace.go:171","msg":"trace[566588328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1989; }","duration":"589.771817ms","start":"2026-08-22T10:36:00.637351Z","end":"2026-08-22T10:36:01.227122Z","steps":["trace[566588328] 'agreement among raft nodes before linearized reading' (duration: 589.726522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:01.227128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.646832Z","time spent":"580.288529ms","remote":"127.0.0.1:43686","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":"warn","ts":"2026-08-22T10:36:01.227142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.637331Z","time spent":"589.80804ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T10:36:01.227064Z","caller":"traceutil/trace.go:171","msg":"trace[796420413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1989; }","duration":"613.785981ms","start":"2026-08-22T10:36:00.613261Z","end":"2026-08-22T10:36:01.227047Z","steps":["trace[796420413] 'agreement among raft nodes before linearized reading' (duration: 613.665638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:01.227169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.613249Z","time spent":"613.915484ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T10:36:01.22726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.714086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-08-22T10:36:01.227287Z","caller":"traceutil/trace.go:171","msg":"trace[1304094586] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1989; }","duration":"165.774432ms","start":"2026-08-22T10:36:01.061504Z","end":"2026-08-22T10:36:01.227279Z","steps":["trace[1304094586] 'agreement among raft nodes before linearized reading' (duration: 165.698254ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:23.484802Z","caller":"traceutil/trace.go:171","msg":"trace[646021526] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2218; }","duration":"189.359283ms","start":"2026-08-22T10:36:23.295417Z","end":"2026-08-22T10:36:23.484776Z","steps":["trace[646021526] 'read index received' (duration: 28.102052ms)","trace[646021526] 'applied index is now lower than readState.Index' (duration: 161.255841ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:36:23.484985Z","caller":"traceutil/trace.go:171","msg":"trace[145548779] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"190.457194ms","start":"2026-08-22T10:36:23.294405Z","end":"2026-08-22T10:36:23.484862Z","steps":["trace[145548779] 'process raft request' (duration: 157.913102ms)","trace[145548779] 'compare' (duration: 32.349796ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:36:23.485325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.164731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-22T10:36:23.485081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.637441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11666"} {"level":"info","ts":"2026-08-22T10:36:23.485423Z","caller":"traceutil/trace.go:171","msg":"trace[405431446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2149; }","duration":"140.274604ms","start":"2026-08-22T10:36:23.345126Z","end":"2026-08-22T10:36:23.4854Z","steps":["trace[405431446] 'agreement among raft nodes before linearized reading' (duration: 140.030446ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:23.485467Z","caller":"traceutil/trace.go:171","msg":"trace[63809263] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2149; }","duration":"190.042412ms","start":"2026-08-22T10:36:23.295411Z","end":"2026-08-22T10:36:23.485453Z","steps":["trace[63809263] 'agreement among raft nodes before linearized reading' (duration: 189.511227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.485144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.150178ms","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-08-22T10:36:23.48557Z","caller":"traceutil/trace.go:171","msg":"trace[1738700132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2149; }","duration":"137.57071ms","start":"2026-08-22T10:36:23.347958Z","end":"2026-08-22T10:36:23.485528Z","steps":["trace[1738700132] 'agreement among raft nodes before linearized reading' (duration: 137.058226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.485165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.591484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-22T10:36:23.485789Z","caller":"traceutil/trace.go:171","msg":"trace[2030118162] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2149; }","duration":"154.252912ms","start":"2026-08-22T10:36:23.331515Z","end":"2026-08-22T10:36:23.485768Z","steps":["trace[2030118162] 'agreement among raft nodes before linearized reading' (duration: 153.48822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.485196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.783717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-22T10:36:23.486173Z","caller":"traceutil/trace.go:171","msg":"trace[1893535616] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2149; }","duration":"106.622441ms","start":"2026-08-22T10:36:23.379378Z","end":"2026-08-22T10:36:23.486Z","steps":["trace[1893535616] 'agreement among raft nodes before linearized reading' (duration: 105.745597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.485203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.770192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:36:23.486275Z","caller":"traceutil/trace.go:171","msg":"trace[603443808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2149; }","duration":"101.868762ms","start":"2026-08-22T10:36:23.38439Z","end":"2026-08-22T10:36:23.486259Z","steps":["trace[603443808] 'agreement among raft nodes before linearized reading' (duration: 100.668929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.48525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.70134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-22T10:36:23.486501Z","caller":"traceutil/trace.go:171","msg":"trace[863697420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2149; }","duration":"139.954134ms","start":"2026-08-22T10:36:23.346531Z","end":"2026-08-22T10:36:23.486486Z","steps":["trace[863697420] 'agreement among raft nodes before linearized reading' (duration: 138.661638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.485295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.009818ms","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-08-22T10:36:23.486706Z","caller":"traceutil/trace.go:171","msg":"trace[1666686266] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2149; }","duration":"144.430016ms","start":"2026-08-22T10:36:23.342259Z","end":"2026-08-22T10:36:23.486689Z","steps":["trace[1666686266] 'agreement among raft nodes before linearized reading' (duration: 142.958596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.485295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.477826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T10:36:23.487112Z","caller":"traceutil/trace.go:171","msg":"trace[1127486001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2149; }","duration":"150.297425ms","start":"2026-08-22T10:36:23.336797Z","end":"2026-08-22T10:36:23.487094Z","steps":["trace[1127486001] 'agreement among raft nodes before linearized reading' (duration: 148.431254ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:23.67272Z","caller":"traceutil/trace.go:171","msg":"trace[2044871474] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"181.794158ms","start":"2026-08-22T10:36:23.490894Z","end":"2026-08-22T10:36:23.672689Z","steps":["trace[2044871474] 'process raft request' (duration: 147.131608ms)","trace[2044871474] 'compare' (duration: 34.521626ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:36:23.827555Z","caller":"traceutil/trace.go:171","msg":"trace[26429402] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"335.68065ms","start":"2026-08-22T10:36:23.491851Z","end":"2026-08-22T10:36:23.827531Z","steps":["trace[26429402] 'process raft request' (duration: 335.502166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.827707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:23.491842Z","time spent":"335.780864ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:36:23.827945Z","caller":"traceutil/trace.go:171","msg":"trace[484259860] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"335.850025ms","start":"2026-08-22T10:36:23.492078Z","end":"2026-08-22T10:36:23.827928Z","steps":["trace[484259860] 'process raft request' (duration: 335.417753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.82823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:23.492073Z","time spent":"335.954469ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:36:23.828545Z","caller":"traceutil/trace.go:171","msg":"trace[919937129] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"336.359159ms","start":"2026-08-22T10:36:23.492161Z","end":"2026-08-22T10:36:23.82852Z","steps":["trace[919937129] 'process raft request' (duration: 335.707101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.82869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:23.4921Z","time spent":"336.512833ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:36:23.828751Z","caller":"traceutil/trace.go:171","msg":"trace[1164460879] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"335.752682ms","start":"2026-08-22T10:36:23.492976Z","end":"2026-08-22T10:36:23.828729Z","steps":["trace[1164460879] 'process raft request' (duration: 335.443194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.828862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:23.492963Z","time spent":"335.839345ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:36:23.828962Z","caller":"traceutil/trace.go:171","msg":"trace[162692787] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"335.555347ms","start":"2026-08-22T10:36:23.493384Z","end":"2026-08-22T10:36:23.828939Z","steps":["trace[162692787] 'process raft request' (duration: 335.288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.829055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:23.493362Z","time spent":"335.64307ms","remote":"127.0.0.1:43686","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-08-22T10:36:23.82907Z","caller":"traceutil/trace.go:171","msg":"trace[1252927133] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2220; }","duration":"337.013436ms","start":"2026-08-22T10:36:23.492041Z","end":"2026-08-22T10:36:23.829055Z","steps":["trace[1252927133] 'read index received' (duration: 145.945566ms)","trace[1252927133] 'applied index is now lower than readState.Index' (duration: 191.06673ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:36:23.829133Z","caller":"traceutil/trace.go:171","msg":"trace[920096511] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"306.353625ms","start":"2026-08-22T10:36:23.522765Z","end":"2026-08-22T10:36:23.829119Z","steps":["trace[920096511] 'process raft request' (duration: 306.077187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.829207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:23.522746Z","time spent":"306.422827ms","remote":"127.0.0.1:43866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16381,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:36:23.829235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.185521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-08-22T10:36:23.829266Z","caller":"traceutil/trace.go:171","msg":"trace[363089241] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2156; }","duration":"337.237012ms","start":"2026-08-22T10:36:23.492019Z","end":"2026-08-22T10:36:23.829256Z","steps":["trace[363089241] 'agreement among raft nodes before linearized reading' (duration: 337.098809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.829295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:23.49201Z","time spent":"337.278733ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":589,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T10:36:23.830762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.847779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-08-22T10:36:23.830833Z","caller":"traceutil/trace.go:171","msg":"trace[548575634] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2157; }","duration":"337.938702ms","start":"2026-08-22T10:36:23.492874Z","end":"2026-08-22T10:36:23.830813Z","steps":["trace[548575634] 'agreement among raft nodes before linearized reading' (duration: 337.790418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:23.830864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:23.492854Z","time spent":"338.002094ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":574,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T10:37:14.417371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260845903936873,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:37:14.917714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260845903936873,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:37:14.99158Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.302454458s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T10:37:14.991978Z","caller":"traceutil/trace.go:171","msg":"trace[826429735] linearizableReadLoop","detail":"{readStateIndex:2693; appliedIndex:2692; }","duration":"1.075208822s","start":"2026-08-22T10:37:13.916747Z","end":"2026-08-22T10:37:14.991956Z","steps":["trace[826429735] 'read index received' (duration: 1.074968911s)","trace[826429735] 'applied index is now lower than readState.Index' (duration: 238.971µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:37:14.992121Z","caller":"traceutil/trace.go:171","msg":"trace[1796256500] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"1.303131425s","start":"2026-08-22T10:37:13.688957Z","end":"2026-08-22T10:37:14.992089Z","steps":["trace[1796256500] 'process raft request' (duration: 1.302838858s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:14.99226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07549709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-22T10:37:14.992295Z","caller":"traceutil/trace.go:171","msg":"trace[1916598330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2598; }","duration":"1.075588963s","start":"2026-08-22T10:37:13.916695Z","end":"2026-08-22T10:37:14.992284Z","steps":["trace[1916598330] 'agreement among raft nodes before linearized reading' (duration: 1.075378119s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:14.992296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:13.688928Z","time spent":"1.303272594s","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:37:14.992323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:13.91667Z","time spent":"1.075645648s","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T10:37:14.992403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.220901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:37:14.992454Z","caller":"traceutil/trace.go:171","msg":"trace[831999535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2598; }","duration":"608.311664ms","start":"2026-08-22T10:37:14.384125Z","end":"2026-08-22T10:37:14.992436Z","steps":["trace[831999535] 'agreement among raft nodes before linearized reading' (duration: 608.221181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:14.992471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.656305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-08-22T10:37:14.992493Z","caller":"traceutil/trace.go:171","msg":"trace[1483399331] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2598; }","duration":"239.737929ms","start":"2026-08-22T10:37:14.752749Z","end":"2026-08-22T10:37:14.992487Z","steps":["trace[1483399331] 'agreement among raft nodes before linearized reading' (duration: 239.667094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:14.992476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.709077ms","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":"warn","ts":"2026-08-22T10:37:14.992473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.322727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-22T10:37:14.99249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.074661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-08-22T10:37:14.992538Z","caller":"traceutil/trace.go:171","msg":"trace[776869564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2598; }","duration":"918.80325ms","start":"2026-08-22T10:37:14.07372Z","end":"2026-08-22T10:37:14.992523Z","steps":["trace[776869564] 'agreement among raft nodes before linearized reading' (duration: 918.617544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:14.992572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:14.073712Z","time spent":"918.852375ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T10:37:14.992565Z","caller":"traceutil/trace.go:171","msg":"trace[1455794432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2598; }","duration":"918.443497ms","start":"2026-08-22T10:37:14.0741Z","end":"2026-08-22T10:37:14.992543Z","steps":["trace[1455794432] 'agreement among raft nodes before linearized reading' (duration: 918.200446ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:14.99256Z","caller":"traceutil/trace.go:171","msg":"trace[519713187] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2598; }","duration":"920.192811ms","start":"2026-08-22T10:37:14.072355Z","end":"2026-08-22T10:37:14.992548Z","steps":["trace[519713187] 'agreement among raft nodes before linearized reading' (duration: 919.964259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:14.99267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.063861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"warn","ts":"2026-08-22T10:37:14.992698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:14.072347Z","time spent":"920.34009ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":546,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-08-22T10:37:14.992713Z","caller":"traceutil/trace.go:171","msg":"trace[1147432904] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2598; }","duration":"673.137625ms","start":"2026-08-22T10:37:14.319558Z","end":"2026-08-22T10:37:14.992696Z","steps":["trace[1147432904] 'agreement among raft nodes before linearized reading' (duration: 672.983027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:14.992741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:14.319542Z","time spent":"673.19297ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":666,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T10:37:14.992743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.674272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-22T10:37:14.99278Z","caller":"traceutil/trace.go:171","msg":"trace[2023171283] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2598; }","duration":"673.744597ms","start":"2026-08-22T10:37:14.319024Z","end":"2026-08-22T10:37:14.992769Z","steps":["trace[2023171283] 'agreement among raft nodes before linearized reading' (duration: 673.649745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:14.992495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:14.384111Z","time spent":"608.373299ms","remote":"127.0.0.1:43436","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-08-22T10:37:14.992808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:14.319004Z","time spent":"673.798393ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":681,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T10:37:14.992819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.396442ms","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-08-22T10:37:14.992861Z","caller":"traceutil/trace.go:171","msg":"trace[1368332013] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2598; }","duration":"925.458758ms","start":"2026-08-22T10:37:14.06739Z","end":"2026-08-22T10:37:14.992849Z","steps":["trace[1368332013] 'agreement among raft nodes before linearized reading' (duration: 925.30118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:14.992649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:14.074094Z","time spent":"918.508882ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T10:37:14.992891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:14.06738Z","time spent":"925.504784ms","remote":"127.0.0.1:43686","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-08-22T10:37:14.992978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.324272ms","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-08-22T10:37:14.993015Z","caller":"traceutil/trace.go:171","msg":"trace[1441767097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2598; }","duration":"927.385357ms","start":"2026-08-22T10:37:14.065619Z","end":"2026-08-22T10:37:14.993004Z","steps":["trace[1441767097] 'agreement among raft nodes before linearized reading' (duration: 927.289954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:14.993054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:14.065607Z","time spent":"927.437643ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T10:37:14.994814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050315915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-08-22T10:37:14.998454Z","caller":"traceutil/trace.go:171","msg":"trace[1775396064] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2598; }","duration":"1.053994106s","start":"2026-08-22T10:37:13.94444Z","end":"2026-08-22T10:37:14.998435Z","steps":["trace[1775396064] 'agreement among raft nodes before linearized reading' (duration: 1.047791862s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:14.99903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:13.94441Z","time spent":"1.054603238s","remote":"127.0.0.1:43618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1318,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-22T10:38:05.885503Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260845903937886,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:38:06.341646Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.051946003s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T10:38:06.343073Z","caller":"traceutil/trace.go:171","msg":"trace[1246299042] linearizableReadLoop","detail":"{readStateIndex:3062; appliedIndex:3060; }","duration":"957.828352ms","start":"2026-08-22T10:38:05.385231Z","end":"2026-08-22T10:38:06.34306Z","steps":["trace[1246299042] 'read index received' (duration: 956.533415ms)","trace[1246299042] 'applied index is now lower than readState.Index' (duration: 1.294297ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:38:06.343106Z","caller":"traceutil/trace.go:171","msg":"trace[869450538] transaction","detail":"{read_only:false; response_revision:2945; number_of_response:1; }","duration":"1.053437276s","start":"2026-08-22T10:38:05.28964Z","end":"2026-08-22T10:38:06.343077Z","steps":["trace[869450538] 'process raft request' (duration: 1.052137289s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:06.343244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.00206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:38:06.343144Z","caller":"traceutil/trace.go:171","msg":"trace[1367376900] transaction","detail":"{read_only:false; response_revision:2946; number_of_response:1; }","duration":"1.050979414s","start":"2026-08-22T10:38:05.292157Z","end":"2026-08-22T10:38:06.343137Z","steps":["trace[1367376900] 'process raft request' (duration: 1.05085602s)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:38:06.343334Z","caller":"traceutil/trace.go:171","msg":"trace[79648216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2946; }","duration":"958.143222ms","start":"2026-08-22T10:38:05.385184Z","end":"2026-08-22T10:38:06.343328Z","steps":["trace[79648216] 'agreement among raft nodes before linearized reading' (duration: 957.988761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:06.34336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:38:05.292145Z","time spent":"1.051191437s","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:38:06.343393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:38:05.38517Z","time spent":"958.195395ms","remote":"127.0.0.1:43436","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-08-22T10:38:06.343595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.597407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-22T10:38:06.343618Z","caller":"traceutil/trace.go:171","msg":"trace[2022581074] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2946; }","duration":"784.654069ms","start":"2026-08-22T10:38:05.558957Z","end":"2026-08-22T10:38:06.343611Z","steps":["trace[2022581074] 'agreement among raft nodes before linearized reading' (duration: 784.586968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:06.343664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:38:05.558938Z","time spent":"784.709792ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":681,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T10:38:06.343285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:38:05.289627Z","time spent":"1.05356126s","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:38:06.344141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.837923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:38:06.34417Z","caller":"traceutil/trace.go:171","msg":"trace[1808042139] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2946; }","duration":"233.906015ms","start":"2026-08-22T10:38:06.110257Z","end":"2026-08-22T10:38:06.344163Z","steps":["trace[1808042139] 'agreement among raft nodes before linearized reading' (duration: 233.855182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:06.344277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.590278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-08-22T10:38:06.344355Z","caller":"traceutil/trace.go:171","msg":"trace[1588723416] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2946; }","duration":"780.678217ms","start":"2026-08-22T10:38:05.563658Z","end":"2026-08-22T10:38:06.344336Z","steps":["trace[1588723416] 'agreement among raft nodes before linearized reading' (duration: 780.470344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:06.344364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.210415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T10:38:06.344403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:38:05.56364Z","time spent":"780.752018ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":666,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-22T10:38:06.344412Z","caller":"traceutil/trace.go:171","msg":"trace[738030537] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2946; }","duration":"499.284086ms","start":"2026-08-22T10:38:05.845116Z","end":"2026-08-22T10:38:06.3444Z","steps":["trace[738030537] 'agreement among raft nodes before linearized reading' (duration: 499.189878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:06.344447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:38:05.845079Z","time spent":"499.360146ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":30,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:38:06.3446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.246463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-08-22T10:38:06.34463Z","caller":"traceutil/trace.go:171","msg":"trace[1075538105] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2946; }","duration":"400.295867ms","start":"2026-08-22T10:38:05.944326Z","end":"2026-08-22T10:38:06.344622Z","steps":["trace[1075538105] 'agreement among raft nodes before linearized reading' (duration: 400.19099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:06.344659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:38:05.944312Z","time spent":"400.341351ms","remote":"127.0.0.1:43618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1318,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-22T10:38:06.344632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.951248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:38:06.34488Z","caller":"traceutil/trace.go:171","msg":"trace[1641420759] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2946; }","duration":"619.223214ms","start":"2026-08-22T10:38:05.725644Z","end":"2026-08-22T10:38:06.344867Z","steps":["trace[1641420759] 'agreement among raft nodes before linearized reading' (duration: 618.93796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:06.34492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:38:05.72563Z","time spent":"619.279747ms","remote":"127.0.0.1:43660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-08-22T10:39:50.339309Z","caller":"traceutil/trace.go:171","msg":"trace[1094880098] transaction","detail":"{read_only:false; response_revision:3895; number_of_response:1; }","duration":"103.945163ms","start":"2026-08-22T10:39:50.235336Z","end":"2026-08-22T10:39:50.339281Z","steps":["trace[1094880098] 'process raft request' (duration: 88.311546ms)","trace[1094880098] 'compare' (duration: 15.439763ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:39:50.339382Z","caller":"traceutil/trace.go:171","msg":"trace[971706093] transaction","detail":"{read_only:false; response_revision:3896; number_of_response:1; }","duration":"103.980709ms","start":"2026-08-22T10:39:50.235393Z","end":"2026-08-22T10:39:50.339374Z","steps":["trace[971706093] 'process raft request' (duration: 103.809609ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:39:50.339307Z","caller":"traceutil/trace.go:171","msg":"trace[1266680164] transaction","detail":"{read_only:false; response_revision:3897; number_of_response:1; }","duration":"103.854377ms","start":"2026-08-22T10:39:50.23543Z","end":"2026-08-22T10:39:50.339284Z","steps":["trace[1266680164] 'process raft request' (duration: 103.81363ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:39:50.880491Z","caller":"traceutil/trace.go:171","msg":"trace[1383694463] transaction","detail":"{read_only:false; response_revision:3935; number_of_response:1; }","duration":"104.637594ms","start":"2026-08-22T10:39:50.775784Z","end":"2026-08-22T10:39:50.880422Z","steps":["trace[1383694463] 'process raft request' (duration: 68.168947ms)","trace[1383694463] 'compare' (duration: 36.189989ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:39:55.795849Z","caller":"traceutil/trace.go:171","msg":"trace[912778383] transaction","detail":"{read_only:false; response_revision:4241; number_of_response:1; }","duration":"176.935171ms","start":"2026-08-22T10:39:55.618867Z","end":"2026-08-22T10:39:55.795802Z","steps":["trace[912778383] 'process raft request' (duration: 176.644583ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:39:55.796036Z","caller":"traceutil/trace.go:171","msg":"trace[1340887720] linearizableReadLoop","detail":"{readStateIndex:4427; appliedIndex:4425; }","duration":"130.29576ms","start":"2026-08-22T10:39:55.665649Z","end":"2026-08-22T10:39:55.795944Z","steps":["trace[1340887720] 'read index received' (duration: 129.98558ms)","trace[1340887720] 'applied index is now lower than readState.Index' (duration: 308.26µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:39:55.79637Z","caller":"traceutil/trace.go:171","msg":"trace[878052278] transaction","detail":"{read_only:false; response_revision:4242; number_of_response:1; }","duration":"171.893302ms","start":"2026-08-22T10:39:55.624383Z","end":"2026-08-22T10:39:55.796276Z","steps":["trace[878052278] 'process raft request' (duration: 171.297374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:39:55.796638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.154268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:46610"} {"level":"info","ts":"2026-08-22T10:39:55.796693Z","caller":"traceutil/trace.go:171","msg":"trace[1956863194] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4242; }","duration":"177.245563ms","start":"2026-08-22T10:39:55.619434Z","end":"2026-08-22T10:39:55.79668Z","steps":["trace[1956863194] 'agreement among raft nodes before linearized reading' (duration: 176.777025ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:39:55.804486Z","caller":"traceutil/trace.go:171","msg":"trace[1052164942] transaction","detail":"{read_only:false; response_revision:4243; number_of_response:1; }","duration":"133.681927ms","start":"2026-08-22T10:39:55.670779Z","end":"2026-08-22T10:39:55.804461Z","steps":["trace[1052164942] 'process raft request' (duration: 133.563658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:39:55.806569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.529522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-22T10:39:55.806626Z","caller":"traceutil/trace.go:171","msg":"trace[6973295] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4243; }","duration":"135.608625ms","start":"2026-08-22T10:39:55.671005Z","end":"2026-08-22T10:39:55.806614Z","steps":["trace[6973295] 'agreement among raft nodes before linearized reading' (duration: 135.382157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:39:55.806981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.045175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9482"} {"level":"info","ts":"2026-08-22T10:39:55.80701Z","caller":"traceutil/trace.go:171","msg":"trace[1915359099] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4243; }","duration":"136.098794ms","start":"2026-08-22T10:39:55.670903Z","end":"2026-08-22T10:39:55.807002Z","steps":["trace[1915359099] 'agreement among raft nodes before linearized reading' (duration: 136.01065ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:06.697555Z","caller":"traceutil/trace.go:171","msg":"trace[1592118576] linearizableReadLoop","detail":"{readStateIndex:4704; appliedIndex:4703; }","duration":"120.7547ms","start":"2026-08-22T10:40:06.576783Z","end":"2026-08-22T10:40:06.697537Z","steps":["trace[1592118576] 'read index received' (duration: 120.661637ms)","trace[1592118576] 'applied index is now lower than readState.Index' (duration: 91.513µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:40:06.697905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.10776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-42plw-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-08-22T10:40:06.697939Z","caller":"traceutil/trace.go:171","msg":"trace[1848557915] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-42plw-tls; range_end:; response_count:1; response_revision:4510; }","duration":"121.210645ms","start":"2026-08-22T10:40:06.576721Z","end":"2026-08-22T10:40:06.697931Z","steps":["trace[1848557915] 'agreement among raft nodes before linearized reading' (duration: 121.080646ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:06.844538Z","caller":"traceutil/trace.go:171","msg":"trace[1112665868] transaction","detail":"{read_only:false; response_revision:4511; number_of_response:1; }","duration":"144.67592ms","start":"2026-08-22T10:40:06.69983Z","end":"2026-08-22T10:40:06.844506Z","steps":["trace[1112665868] 'process raft request' (duration: 121.197213ms)","trace[1112665868] 'compare' (duration: 23.307023ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:06.844599Z","caller":"traceutil/trace.go:171","msg":"trace[112989962] linearizableReadLoop","detail":"{readStateIndex:4705; appliedIndex:4704; }","duration":"140.50578ms","start":"2026-08-22T10:40:06.704068Z","end":"2026-08-22T10:40:06.844574Z","steps":["trace[112989962] 'read index received' (duration: 116.920987ms)","trace[112989962] 'applied index is now lower than readState.Index' (duration: 23.583492ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:40:06.844919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.817204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-42plw-tls\" ","response":"range_response_count:1 size:4432"} {"level":"info","ts":"2026-08-22T10:40:06.845012Z","caller":"traceutil/trace.go:171","msg":"trace[9573951] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-42plw-tls; range_end:; response_count:1; response_revision:4511; }","duration":"140.947672ms","start":"2026-08-22T10:40:06.704049Z","end":"2026-08-22T10:40:06.844996Z","steps":["trace[9573951] 'agreement among raft nodes before linearized reading' (duration: 140.662992ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:06.862004Z","caller":"traceutil/trace.go:171","msg":"trace[477934069] transaction","detail":"{read_only:false; response_revision:4512; number_of_response:1; }","duration":"153.553694ms","start":"2026-08-22T10:40:06.708414Z","end":"2026-08-22T10:40:06.861968Z","steps":["trace[477934069] 'process raft request' (duration: 153.319851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.123158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260845903941698,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:40:10.624155Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321260845903941698,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:40:10.642391Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.020843781s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T10:40:10.642836Z","caller":"traceutil/trace.go:171","msg":"trace[811079372] transaction","detail":"{read_only:false; response_revision:4559; number_of_response:1; }","duration":"1.02136387s","start":"2026-08-22T10:40:09.621452Z","end":"2026-08-22T10:40:10.642816Z","steps":["trace[811079372] 'process raft request' (duration: 1.021168803s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.642972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:09.62143Z","time spent":"1.021452982s","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:40:10.646738Z","caller":"traceutil/trace.go:171","msg":"trace[858467106] transaction","detail":"{read_only:false; response_revision:4560; number_of_response:1; }","duration":"947.429559ms","start":"2026-08-22T10:40:09.699293Z","end":"2026-08-22T10:40:10.646723Z","steps":["trace[858467106] 'process raft request' (duration: 947.241874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.646837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:09.699274Z","time spent":"947.500659ms","remote":"127.0.0.1:43686","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-08-22T10:40:10.646897Z","caller":"traceutil/trace.go:171","msg":"trace[47348237] transaction","detail":"{read_only:false; response_revision:4561; number_of_response:1; }","duration":"911.060829ms","start":"2026-08-22T10:40:09.735811Z","end":"2026-08-22T10:40:10.646872Z","steps":["trace[47348237] 'process raft request' (duration: 910.857162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.647026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:09.735789Z","time spent":"911.169614ms","remote":"127.0.0.1:43508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:40:10.647051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024583827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-08-22T10:40:10.647093Z","caller":"traceutil/trace.go:171","msg":"trace[1313437042] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4561; }","duration":"1.024639145s","start":"2026-08-22T10:40:09.622442Z","end":"2026-08-22T10:40:10.647081Z","steps":["trace[1313437042] 'agreement among raft nodes before linearized reading' (duration: 1.024492646s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.647132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:09.622435Z","time spent":"1.024690302s","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":590,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-22T10:40:10.64734Z","caller":"traceutil/trace.go:171","msg":"trace[906023389] linearizableReadLoop","detail":"{readStateIndex:4756; appliedIndex:4753; }","duration":"1.024379601s","start":"2026-08-22T10:40:09.622457Z","end":"2026-08-22T10:40:10.646837Z","steps":["trace[906023389] 'read index received' (duration: 1.02018088s)","trace[906023389] 'applied index is now lower than readState.Index' (duration: 4.19664ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:40:10.64755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.59309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-263e55ed-864c-4675-a3e0-427307f07bda\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-08-22T10:40:10.647587Z","caller":"traceutil/trace.go:171","msg":"trace[75437600] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-263e55ed-864c-4675-a3e0-427307f07bda; range_end:; response_count:1; response_revision:4561; }","duration":"930.672241ms","start":"2026-08-22T10:40:09.716904Z","end":"2026-08-22T10:40:10.647577Z","steps":["trace[75437600] 'agreement among raft nodes before linearized reading' (duration: 930.525321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.647586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.373538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-08-22T10:40:10.647621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:09.716888Z","time spent":"930.723508ms","remote":"127.0.0.1:43612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3974,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-263e55ed-864c-4675-a3e0-427307f07bda\" "} {"level":"info","ts":"2026-08-22T10:40:10.647635Z","caller":"traceutil/trace.go:171","msg":"trace[1723421035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4561; }","duration":"634.449239ms","start":"2026-08-22T10:40:10.013171Z","end":"2026-08-22T10:40:10.64762Z","steps":["trace[1723421035] 'agreement among raft nodes before linearized reading' (duration: 634.30754ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.648386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:10.013158Z","time spent":"635.21556ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T10:40:10.647885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.045947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-08-22T10:40:10.648474Z","caller":"traceutil/trace.go:171","msg":"trace[1893769837] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4561; }","duration":"265.672052ms","start":"2026-08-22T10:40:10.382782Z","end":"2026-08-22T10:40:10.648454Z","steps":["trace[1893769837] 'agreement among raft nodes before linearized reading' (duration: 264.960656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.64797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.881836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-22T10:40:10.648617Z","caller":"traceutil/trace.go:171","msg":"trace[1991614762] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4561; }","duration":"629.539064ms","start":"2026-08-22T10:40:10.019061Z","end":"2026-08-22T10:40:10.6486Z","steps":["trace[1991614762] 'agreement among raft nodes before linearized reading' (duration: 628.832129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.648649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:10.019052Z","time spent":"629.58804ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":547,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-08-22T10:40:10.648161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.326792ms","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-08-22T10:40:10.648775Z","caller":"traceutil/trace.go:171","msg":"trace[653060096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4561; }","duration":"703.994481ms","start":"2026-08-22T10:40:09.944773Z","end":"2026-08-22T10:40:10.648768Z","steps":["trace[653060096] 'agreement among raft nodes before linearized reading' (duration: 703.218467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.648797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:09.944758Z","time spent":"704.032386ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T10:40:10.648199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.272198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-22T10:40:10.648868Z","caller":"traceutil/trace.go:171","msg":"trace[686071503] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4561; }","duration":"819.982963ms","start":"2026-08-22T10:40:09.828872Z","end":"2026-08-22T10:40:10.648855Z","steps":["trace[686071503] 'agreement among raft nodes before linearized reading' (duration: 819.267657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.64891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:09.828852Z","time spent":"820.047522ms","remote":"127.0.0.1:43588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-08-22T10:40:10.64824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.953646ms","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-08-22T10:40:10.648973Z","caller":"traceutil/trace.go:171","msg":"trace[523931839] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4561; }","duration":"638.700495ms","start":"2026-08-22T10:40:10.010262Z","end":"2026-08-22T10:40:10.648962Z","steps":["trace[523931839] 'agreement among raft nodes before linearized reading' (duration: 637.937433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.649012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:10.010252Z","time spent":"638.752863ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T10:40:10.648236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.308737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-263e55ed-864c-4675-a3e0-427307f07bda\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-08-22T10:40:10.649108Z","caller":"traceutil/trace.go:171","msg":"trace[1381995706] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-263e55ed-864c-4675-a3e0-427307f07bda; range_end:; response_count:1; response_revision:4561; }","duration":"917.22822ms","start":"2026-08-22T10:40:09.731871Z","end":"2026-08-22T10:40:10.649099Z","steps":["trace[1381995706] 'agreement among raft nodes before linearized reading' (duration: 916.301055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.649134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:09.731839Z","time spent":"917.286048ms","remote":"127.0.0.1:43612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3974,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-263e55ed-864c-4675-a3e0-427307f07bda\" "} {"level":"warn","ts":"2026-08-22T10:40:10.648276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.164574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:40:10.649209Z","caller":"traceutil/trace.go:171","msg":"trace[1170627119] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:4561; }","duration":"650.164737ms","start":"2026-08-22T10:40:09.999037Z","end":"2026-08-22T10:40:10.649202Z","steps":["trace[1170627119] 'agreement among raft nodes before linearized reading' (duration: 649.21673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:10.649235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:09.999026Z","time spent":"650.200992ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:40:20.063346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.357625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:40:20.064164Z","caller":"traceutil/trace.go:171","msg":"trace[1072572593] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4709; }","duration":"451.19633ms","start":"2026-08-22T10:40:19.61294Z","end":"2026-08-22T10:40:20.064137Z","steps":["trace[1072572593] 'count revisions from in-memory index tree' (duration: 450.250183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:20.064284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:19.612868Z","time spent":"451.380912ms","remote":"127.0.0.1:37956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:40:20.063464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.045252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-42plw\" ","response":"range_response_count:1 size:9942"} {"level":"info","ts":"2026-08-22T10:40:20.064472Z","caller":"traceutil/trace.go:171","msg":"trace[1626319152] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-42plw; range_end:; response_count:1; response_revision:4709; }","duration":"249.114274ms","start":"2026-08-22T10:40:19.815326Z","end":"2026-08-22T10:40:20.06444Z","steps":["trace[1626319152] 'range keys from in-memory index tree' (duration: 247.791032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:20.063583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.613616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-22T10:40:20.064555Z","caller":"traceutil/trace.go:171","msg":"trace[237806660] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4709; }","duration":"176.708821ms","start":"2026-08-22T10:40:19.887832Z","end":"2026-08-22T10:40:20.064541Z","steps":["trace[237806660] 'range keys from in-memory index tree' (duration: 175.285179ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:20.192027Z","caller":"traceutil/trace.go:171","msg":"trace[185805204] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"115.701638ms","start":"2026-08-22T10:40:20.076284Z","end":"2026-08-22T10:40:20.191985Z","steps":["trace[185805204] 'process raft request' (duration: 115.442588ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:55.074652Z","caller":"traceutil/trace.go:171","msg":"trace[1748088053] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"135.417738ms","start":"2026-08-22T10:40:54.939196Z","end":"2026-08-22T10:40:55.074614Z","steps":["trace[1748088053] 'process raft request' (duration: 117.371812ms)","trace[1748088053] 'compare' (duration: 17.646783ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:55.07487Z","caller":"traceutil/trace.go:171","msg":"trace[1183084037] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"135.63598ms","start":"2026-08-22T10:40:54.939205Z","end":"2026-08-22T10:40:55.074841Z","steps":["trace[1183084037] 'process raft request' (duration: 135.336673ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:55.074732Z","caller":"traceutil/trace.go:171","msg":"trace[1569467705] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"134.390795ms","start":"2026-08-22T10:40:54.940314Z","end":"2026-08-22T10:40:55.074705Z","steps":["trace[1569467705] 'process raft request' (duration: 134.346883ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:05.764753Z","caller":"traceutil/trace.go:171","msg":"trace[1954801518] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5190; }","duration":"111.649534ms","start":"2026-08-22T10:41:05.653074Z","end":"2026-08-22T10:41:05.764723Z","steps":["trace[1954801518] 'process raft request' (duration: 41.695033ms)","trace[1954801518] 'compare' (duration: 69.889408ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:33.719897Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3230} {"level":"info","ts":"2026-08-22T10:43:33.85433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3230,"took":"132.293441ms","hash":1829596300,"current-db-size-bytes":59514880,"current-db-size":"60 MB","current-db-size-in-use-bytes":32530432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T10:43:33.854427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1829596300,"revision":3230,"compact-revision":-1}