{"level":"warn","ts":"2026-05-20T06:02:44.563395Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T06:02:44.563474Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.30: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.30:2380","--initial-cluster=instance=https://199.19.213.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.30: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-05-20T06:02:44.563536Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T06:02:44.563547Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-05-20T06:02:44.563581Z","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-05-20T06:02:44.564174Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"]} {"level":"info","ts":"2026-05-20T06:02:44.564244Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.30: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-05-20T06:02:44.569625Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.180133ms"} {"level":"info","ts":"2026-05-20T06:02:44.582284Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e2b5783e11defe7e","cluster-id":"5210a9231a779bf0"} {"level":"info","ts":"2026-05-20T06:02:44.582498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T06:02:44.582552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 0"} {"level":"info","ts":"2026-05-20T06:02:44.582594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e2b5783e11defe7e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T06:02:44.582613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 1"} {"level":"info","ts":"2026-05-20T06:02:44.582681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"warn","ts":"2026-05-20T06:02:44.589654Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T06:02:44.591409Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T06:02:44.593848Z","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-05-20T06:02:44.596966Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e2b5783e11defe7e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T06:02:44.597236Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e2b5783e11defe7e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T06:02:44.597321Z","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-05-20T06:02:44.598584Z","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-05-20T06:02:44.598614Z","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-05-20T06:02:44.598954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"info","ts":"2026-05-20T06:02:44.599834Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","added-peer-id":"e2b5783e11defe7e","added-peer-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-05-20T06:02:44.602667Z","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-05-20T06:02:44.602926Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-05-20T06:02:44.602987Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-05-20T06:02:44.602992Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e2b5783e11defe7e","initial-advertise-peer-urls":["https://199.19.213.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T06:02:44.603069Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T06:02:44.683173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T06:02:44.683236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T06:02:44.683278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgPreVoteResp from e2b5783e11defe7e at term 1"} {"level":"info","ts":"2026-05-20T06:02:44.6833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became candidate at term 2"} {"level":"info","ts":"2026-05-20T06:02:44.683311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgVoteResp from e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-05-20T06:02:44.683329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became leader at term 2"} {"level":"info","ts":"2026-05-20T06:02:44.683349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e2b5783e11defe7e elected leader e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-05-20T06:02:44.684418Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T06:02:44.685503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T06:02:44.685499Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e2b5783e11defe7e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.30:2379]}","request-path":"/0/members/e2b5783e11defe7e/attributes","cluster-id":"5210a9231a779bf0","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T06:02:44.685572Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T06:02:44.68592Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T06:02:44.686022Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T06:02:44.686076Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T06:02:44.686294Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T06:02:44.686344Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T06:02:44.688714Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.30:2379"} {"level":"info","ts":"2026-05-20T06:02:44.697464Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T06:02:58.432098Z","caller":"traceutil/trace.go:171","msg":"trace[2025844872] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"210.404848ms","start":"2026-05-20T06:02:58.221662Z","end":"2026-05-20T06:02:58.432067Z","steps":["trace[2025844872] 'process raft request' (duration: 176.964537ms)","trace[2025844872] 'compare' (duration: 33.282237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:02:58.783256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.981292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T06:02:58.783374Z","caller":"traceutil/trace.go:171","msg":"trace[509541309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:366; }","duration":"198.161835ms","start":"2026-05-20T06:02:58.585191Z","end":"2026-05-20T06:02:58.783353Z","steps":["trace[509541309] 'range keys from in-memory index tree' (duration: 197.86699ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:03:23.032076Z","caller":"traceutil/trace.go:171","msg":"trace[442455092] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"131.949177ms","start":"2026-05-20T06:03:22.900082Z","end":"2026-05-20T06:03:23.032031Z","steps":["trace[442455092] 'read index received' (duration: 131.56866ms)","trace[442455092] 'applied index is now lower than readState.Index' (duration: 379.027µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:03:23.032226Z","caller":"traceutil/trace.go:171","msg":"trace[460365891] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"286.525752ms","start":"2026-05-20T06:03:22.745637Z","end":"2026-05-20T06:03:23.032163Z","steps":["trace[460365891] 'process raft request' (duration: 286.085153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:03:23.032432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.314564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T06:03:23.032481Z","caller":"traceutil/trace.go:171","msg":"trace[189052095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"132.424667ms","start":"2026-05-20T06:03:22.900039Z","end":"2026-05-20T06:03:23.032463Z","steps":["trace[189052095] 'agreement among raft nodes before linearized reading' (duration: 132.203982ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:03:23.508545Z","caller":"traceutil/trace.go:171","msg":"trace[1187463109] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"356.614287ms","start":"2026-05-20T06:03:23.151894Z","end":"2026-05-20T06:03:23.508508Z","steps":["trace[1187463109] 'read index received' (duration: 330.366488ms)","trace[1187463109] 'applied index is now lower than readState.Index' (duration: 26.245589ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:03:23.508704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.79207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:03:23.508737Z","caller":"traceutil/trace.go:171","msg":"trace[1051659124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"356.885522ms","start":"2026-05-20T06:03:23.151841Z","end":"2026-05-20T06:03:23.508726Z","steps":["trace[1051659124] 'agreement among raft nodes before linearized reading' (duration: 356.7961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:03:23.509003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:03:23.151821Z","time spent":"357.165057ms","remote":"127.0.0.1:58718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-20T06:04:09.029694Z","caller":"traceutil/trace.go:171","msg":"trace[1000385193] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"144.828459ms","start":"2026-05-20T06:04:08.884835Z","end":"2026-05-20T06:04:09.029664Z","steps":["trace[1000385193] 'process raft request' (duration: 144.647556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:04:29.320309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.038774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:04:29.320435Z","caller":"traceutil/trace.go:171","msg":"trace[1322684214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1396; }","duration":"168.194718ms","start":"2026-05-20T06:04:29.152209Z","end":"2026-05-20T06:04:29.320404Z","steps":["trace[1322684214] 'agreement among raft nodes before linearized reading' (duration: 62.714967ms)","trace[1322684214] 'range keys from in-memory index tree' (duration: 105.319468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:04:29.320687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.842486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-20T06:04:29.320751Z","caller":"traceutil/trace.go:171","msg":"trace[216163704] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1396; }","duration":"186.940877ms","start":"2026-05-20T06:04:29.133794Z","end":"2026-05-20T06:04:29.320734Z","steps":["trace[216163704] 'agreement among raft nodes before linearized reading' (duration: 81.15904ms)","trace[216163704] 'range keys from in-memory index tree' (duration: 105.610353ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:04:56.597119Z","caller":"traceutil/trace.go:171","msg":"trace[1575353361] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1879; }","duration":"226.420207ms","start":"2026-05-20T06:04:56.370673Z","end":"2026-05-20T06:04:56.597093Z","steps":["trace[1575353361] 'read index received' (duration: 226.12288ms)","trace[1575353361] 'applied index is now lower than readState.Index' (duration: 296.307µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:04:56.597196Z","caller":"traceutil/trace.go:171","msg":"trace[433849195] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"321.266988ms","start":"2026-05-20T06:04:56.275882Z","end":"2026-05-20T06:04:56.597149Z","steps":["trace[433849195] 'process raft request' (duration: 320.990761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:04:56.597359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.661231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-05-20T06:04:56.597436Z","caller":"traceutil/trace.go:171","msg":"trace[809881649] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1810; }","duration":"226.779314ms","start":"2026-05-20T06:04:56.370618Z","end":"2026-05-20T06:04:56.597397Z","steps":["trace[809881649] 'agreement among raft nodes before linearized reading' (duration: 226.58879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:04:56.597989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:04:56.275862Z","time spent":"321.440801ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:05:40.337155Z","caller":"traceutil/trace.go:171","msg":"trace[279837617] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2184; }","duration":"107.180903ms","start":"2026-05-20T06:05:40.229951Z","end":"2026-05-20T06:05:40.337132Z","steps":["trace[279837617] 'read index received' (duration: 107.00246ms)","trace[279837617] 'applied index is now lower than readState.Index' (duration: 177.763µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:05:40.337288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.316786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:05:40.337312Z","caller":"traceutil/trace.go:171","msg":"trace[161095919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2105; }","duration":"107.393038ms","start":"2026-05-20T06:05:40.229912Z","end":"2026-05-20T06:05:40.337305Z","steps":["trace[161095919] 'agreement among raft nodes before linearized reading' (duration: 107.315206ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:05:40.337452Z","caller":"traceutil/trace.go:171","msg":"trace[723144287] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"111.033039ms","start":"2026-05-20T06:05:40.226328Z","end":"2026-05-20T06:05:40.337361Z","steps":["trace[723144287] 'process raft request' (duration: 110.677122ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:05:46.488894Z","caller":"traceutil/trace.go:171","msg":"trace[318194012] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"133.130466ms","start":"2026-05-20T06:05:46.355741Z","end":"2026-05-20T06:05:46.488872Z","steps":["trace[318194012] 'process raft request' (duration: 132.989153ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:06:31.204956Z","caller":"traceutil/trace.go:171","msg":"trace[1185740794] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"105.889599ms","start":"2026-05-20T06:06:31.099027Z","end":"2026-05-20T06:06:31.204917Z","steps":["trace[1185740794] 'process raft request' (duration: 105.604643ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:07:04.382064Z","caller":"traceutil/trace.go:171","msg":"trace[576925778] linearizableReadLoop","detail":"{readStateIndex:2706; appliedIndex:2704; }","duration":"175.942503ms","start":"2026-05-20T06:07:04.206104Z","end":"2026-05-20T06:07:04.382047Z","steps":["trace[576925778] 'read index received' (duration: 111.365581ms)","trace[576925778] 'applied index is now lower than readState.Index' (duration: 64.576092ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:07:04.382066Z","caller":"traceutil/trace.go:171","msg":"trace[2061195952] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"177.035054ms","start":"2026-05-20T06:07:04.205004Z","end":"2026-05-20T06:07:04.38204Z","steps":["trace[2061195952] 'process raft request' (duration: 112.3529ms)","trace[2061195952] 'compare' (duration: 64.44326ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:07:04.382393Z","caller":"traceutil/trace.go:171","msg":"trace[1660501710] transaction","detail":"{read_only:false; response_revision:2607; number_of_response:1; }","duration":"176.600466ms","start":"2026-05-20T06:07:04.205754Z","end":"2026-05-20T06:07:04.382354Z","steps":["trace[1660501710] 'process raft request' (duration: 176.224679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:07:04.382737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.521814ms","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-05-20T06:07:04.382442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.319251ms","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-05-20T06:07:04.38281Z","caller":"traceutil/trace.go:171","msg":"trace[770697613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2607; }","duration":"176.631727ms","start":"2026-05-20T06:07:04.206165Z","end":"2026-05-20T06:07:04.382797Z","steps":["trace[770697613] 'agreement among raft nodes before linearized reading' (duration: 176.415243ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:07:04.382885Z","caller":"traceutil/trace.go:171","msg":"trace[1178518886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2607; }","duration":"176.778699ms","start":"2026-05-20T06:07:04.206089Z","end":"2026-05-20T06:07:04.382868Z","steps":["trace[1178518886] 'agreement among raft nodes before linearized reading' (duration: 176.241979ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:09:50.450525Z","caller":"traceutil/trace.go:171","msg":"trace[1710787997] transaction","detail":"{read_only:false; response_revision:3670; number_of_response:1; }","duration":"218.154391ms","start":"2026-05-20T06:09:50.232351Z","end":"2026-05-20T06:09:50.450505Z","steps":["trace[1710787997] 'process raft request' (duration: 218.036239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:11:04.320051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.076125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-20T06:11:04.320123Z","caller":"traceutil/trace.go:171","msg":"trace[1701158708] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4613; }","duration":"110.185587ms","start":"2026-05-20T06:11:04.209923Z","end":"2026-05-20T06:11:04.320108Z","steps":["trace[1701158708] 'range keys from in-memory index tree' (duration: 109.948593ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:11:09.156493Z","caller":"traceutil/trace.go:171","msg":"trace[932202475] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"195.315858ms","start":"2026-05-20T06:11:08.961155Z","end":"2026-05-20T06:11:09.156471Z","steps":["trace[932202475] 'process raft request' (duration: 195.157675ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:11:09.290828Z","caller":"traceutil/trace.go:171","msg":"trace[1448669160] linearizableReadLoop","detail":"{readStateIndex:4991; appliedIndex:4989; }","duration":"140.117182ms","start":"2026-05-20T06:11:09.150691Z","end":"2026-05-20T06:11:09.290808Z","steps":["trace[1448669160] 'read index received' (duration: 5.622192ms)","trace[1448669160] 'applied index is now lower than readState.Index' (duration: 134.49407ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:11:09.290919Z","caller":"traceutil/trace.go:171","msg":"trace[1648142361] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"329.177096ms","start":"2026-05-20T06:11:08.961713Z","end":"2026-05-20T06:11:09.29089Z","steps":["trace[1648142361] 'process raft request' (duration: 328.941291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:11:09.291065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:11:08.96169Z","time spent":"329.307419ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3705,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:11:09.290947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.251385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:11:09.29115Z","caller":"traceutil/trace.go:171","msg":"trace[1380957284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4808; }","duration":"140.482919ms","start":"2026-05-20T06:11:09.150627Z","end":"2026-05-20T06:11:09.29111Z","steps":["trace[1380957284] 'agreement among raft nodes before linearized reading' (duration: 140.272105ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:11:09.296157Z","caller":"traceutil/trace.go:171","msg":"trace[1200038305] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"104.869022ms","start":"2026-05-20T06:11:09.191257Z","end":"2026-05-20T06:11:09.296126Z","steps":["trace[1200038305] 'process raft request' (duration: 104.594207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:11:21.183294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.726829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-rbmn5\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-05-20T06:11:21.183398Z","caller":"traceutil/trace.go:171","msg":"trace[1035884991] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-rbmn5; range_end:; response_count:1; response_revision:5047; }","duration":"106.917222ms","start":"2026-05-20T06:11:21.076459Z","end":"2026-05-20T06:11:21.183376Z","steps":["trace[1035884991] 'range keys from in-memory index tree' (duration: 106.556445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:11:35.319242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.062508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:11:35.319419Z","caller":"traceutil/trace.go:171","msg":"trace[1016923038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5307; }","duration":"167.289892ms","start":"2026-05-20T06:11:35.152107Z","end":"2026-05-20T06:11:35.319397Z","steps":["trace[1016923038] 'range keys from in-memory index tree' (duration: 166.948815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:11:45.834517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.924022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T06:11:45.835122Z","caller":"traceutil/trace.go:171","msg":"trace[1739094171] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"125.40955ms","start":"2026-05-20T06:11:45.709639Z","end":"2026-05-20T06:11:45.835048Z","steps":["trace[1739094171] 'process raft request' (duration: 15.714302ms)","trace[1739094171] 'compare' (duration: 108.648956ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:12:45.060011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2823} {"level":"info","ts":"2026-05-20T06:12:45.123904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2823,"took":"62.322898ms","hash":1143915228,"current-db-size-bytes":56508416,"current-db-size":"56 MB","current-db-size-in-use-bytes":32694272,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-20T06:12:45.123983Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1143915228,"revision":2823,"compact-revision":-1} {"level":"info","ts":"2026-05-20T06:17:35.581093Z","caller":"traceutil/trace.go:171","msg":"trace[878468953] transaction","detail":"{read_only:false; response_revision:8058; number_of_response:1; }","duration":"132.875794ms","start":"2026-05-20T06:17:35.448183Z","end":"2026-05-20T06:17:35.581059Z","steps":["trace[878468953] 'process raft request' (duration: 132.68314ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:17:35.594118Z","caller":"traceutil/trace.go:171","msg":"trace[213817770] transaction","detail":"{read_only:false; response_revision:8059; number_of_response:1; }","duration":"103.094919ms","start":"2026-05-20T06:17:35.490986Z","end":"2026-05-20T06:17:35.594081Z","steps":["trace[213817770] 'process raft request' (duration: 102.817034ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:17:37.157566Z","caller":"traceutil/trace.go:171","msg":"trace[2059960605] transaction","detail":"{read_only:false; response_revision:8065; number_of_response:1; }","duration":"222.904043ms","start":"2026-05-20T06:17:36.934643Z","end":"2026-05-20T06:17:37.157547Z","steps":["trace[2059960605] 'process raft request' (duration: 222.796411ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:17:37.717768Z","caller":"traceutil/trace.go:171","msg":"trace[1293784472] transaction","detail":"{read_only:false; response_revision:8068; number_of_response:1; }","duration":"127.506917ms","start":"2026-05-20T06:17:37.590221Z","end":"2026-05-20T06:17:37.717728Z","steps":["trace[1293784472] 'process raft request' (duration: 127.279713ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:17:37.880056Z","caller":"traceutil/trace.go:171","msg":"trace[579879107] transaction","detail":"{read_only:false; response_revision:8069; number_of_response:1; }","duration":"157.71019ms","start":"2026-05-20T06:17:37.722318Z","end":"2026-05-20T06:17:37.880029Z","steps":["trace[579879107] 'process raft request' (duration: 100.051108ms)","trace[579879107] 'compare' (duration: 57.476248ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:17:37.880331Z","caller":"traceutil/trace.go:171","msg":"trace[906040339] transaction","detail":"{read_only:false; response_revision:8070; number_of_response:1; }","duration":"157.959415ms","start":"2026-05-20T06:17:37.722337Z","end":"2026-05-20T06:17:37.880296Z","steps":["trace[906040339] 'process raft request' (duration: 157.649069ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:17:37.880429Z","caller":"traceutil/trace.go:171","msg":"trace[1497932477] transaction","detail":"{read_only:false; response_revision:8071; number_of_response:1; }","duration":"157.932194ms","start":"2026-05-20T06:17:37.72246Z","end":"2026-05-20T06:17:37.880392Z","steps":["trace[1497932477] 'process raft request' (duration: 157.71763ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:17:37.880501Z","caller":"traceutil/trace.go:171","msg":"trace[2012528093] transaction","detail":"{read_only:false; response_revision:8072; number_of_response:1; }","duration":"157.450835ms","start":"2026-05-20T06:17:37.723039Z","end":"2026-05-20T06:17:37.88049Z","steps":["trace[2012528093] 'process raft request' (duration: 157.268041ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:17:45.069128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5915} {"level":"info","ts":"2026-05-20T06:17:45.148704Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5915,"took":"77.574069ms","hash":2818906549,"current-db-size-bytes":56508416,"current-db-size":"56 MB","current-db-size-in-use-bytes":26497024,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-20T06:17:45.148768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2818906549,"revision":5915,"compact-revision":2823} {"level":"info","ts":"2026-05-20T06:18:16.630799Z","caller":"traceutil/trace.go:171","msg":"trace[1995982423] linearizableReadLoop","detail":"{readStateIndex:8666; appliedIndex:8665; }","duration":"130.930775ms","start":"2026-05-20T06:18:16.499847Z","end":"2026-05-20T06:18:16.630778Z","steps":["trace[1995982423] 'read index received' (duration: 130.854134ms)","trace[1995982423] 'applied index is now lower than readState.Index' (duration: 75.911µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:18:16.630917Z","caller":"traceutil/trace.go:171","msg":"trace[1214347396] transaction","detail":"{read_only:false; response_revision:8370; number_of_response:1; }","duration":"131.994396ms","start":"2026-05-20T06:18:16.4989Z","end":"2026-05-20T06:18:16.630894Z","steps":["trace[1214347396] 'process raft request' (duration: 131.717261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:16.631013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.151489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-20T06:18:16.631043Z","caller":"traceutil/trace.go:171","msg":"trace[1112310392] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8370; }","duration":"131.232891ms","start":"2026-05-20T06:18:16.499803Z","end":"2026-05-20T06:18:16.631036Z","steps":["trace[1112310392] 'agreement among raft nodes before linearized reading' (duration: 131.082858ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:18:16.787568Z","caller":"traceutil/trace.go:171","msg":"trace[482622027] linearizableReadLoop","detail":"{readStateIndex:8667; appliedIndex:8666; }","duration":"156.682079ms","start":"2026-05-20T06:18:16.630864Z","end":"2026-05-20T06:18:16.787546Z","steps":["trace[482622027] 'read index received' (duration: 156.586007ms)","trace[482622027] 'applied index is now lower than readState.Index' (duration: 94.452µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:18:16.787717Z","caller":"traceutil/trace.go:171","msg":"trace[1278631188] transaction","detail":"{read_only:false; response_revision:8371; number_of_response:1; }","duration":"257.876951ms","start":"2026-05-20T06:18:16.529754Z","end":"2026-05-20T06:18:16.787631Z","steps":["trace[1278631188] 'process raft request' (duration: 257.618536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:16.787823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.515204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T06:18:16.787902Z","caller":"traceutil/trace.go:171","msg":"trace[739185783] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8371; }","duration":"224.637167ms","start":"2026-05-20T06:18:16.563243Z","end":"2026-05-20T06:18:16.78788Z","steps":["trace[739185783] 'agreement among raft nodes before linearized reading' (duration: 224.480224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:16.788038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.026114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-69dpn\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-05-20T06:18:16.788109Z","caller":"traceutil/trace.go:171","msg":"trace[812537352] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-69dpn; range_end:; response_count:1; response_revision:8371; }","duration":"259.151066ms","start":"2026-05-20T06:18:16.528941Z","end":"2026-05-20T06:18:16.788092Z","steps":["trace[812537352] 'agreement among raft nodes before linearized reading' (duration: 258.911451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:16.790928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.6902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19316"} {"level":"info","ts":"2026-05-20T06:18:16.790979Z","caller":"traceutil/trace.go:171","msg":"trace[1886294096] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8372; }","duration":"158.782221ms","start":"2026-05-20T06:18:16.632184Z","end":"2026-05-20T06:18:16.790967Z","steps":["trace[1886294096] 'agreement among raft nodes before linearized reading' (duration: 158.640069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:18:17.71499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.556597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T06:18:17.715093Z","caller":"traceutil/trace.go:171","msg":"trace[1587865418] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:8378; }","duration":"167.71155ms","start":"2026-05-20T06:18:17.547359Z","end":"2026-05-20T06:18:17.715071Z","steps":["trace[1587865418] 'count revisions from in-memory index tree' (duration: 167.469646ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:19:17.273689Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002441.wal"} {"level":"info","ts":"2026-05-20T06:20:43.9511Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2b5783e11defe7e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-20T06:20:43.957658Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-20T06:20:43.957753Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-20T06:22:45.075832Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8111} {"level":"info","ts":"2026-05-20T06:22:45.137907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8111,"took":"59.330653ms","hash":2669140920,"current-db-size-bytes":56508416,"current-db-size":"56 MB","current-db-size-in-use-bytes":31002624,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-20T06:22:45.137977Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2669140920,"revision":8111,"compact-revision":5915} {"level":"info","ts":"2026-05-20T06:23:00.635421Z","caller":"traceutil/trace.go:171","msg":"trace[624573844] transaction","detail":"{read_only:false; response_revision:10917; number_of_response:1; }","duration":"161.592825ms","start":"2026-05-20T06:23:00.473777Z","end":"2026-05-20T06:23:00.63537Z","steps":["trace[624573844] 'process raft request' (duration: 78.117928ms)","trace[624573844] 'compare' (duration: 83.305044ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:27:45.086403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10828} {"level":"info","ts":"2026-05-20T06:27:45.168111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10828,"took":"79.107925ms","hash":2391146265,"current-db-size-bytes":56508416,"current-db-size":"56 MB","current-db-size-in-use-bytes":27336704,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-20T06:27:45.168194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2391146265,"revision":10828,"compact-revision":8111} {"level":"info","ts":"2026-05-20T06:29:25.577132Z","caller":"traceutil/trace.go:171","msg":"trace[659286089] linearizableReadLoop","detail":"{readStateIndex:13963; appliedIndex:13962; }","duration":"200.286676ms","start":"2026-05-20T06:29:25.376819Z","end":"2026-05-20T06:29:25.577106Z","steps":["trace[659286089] 'read index received' (duration: 199.96902ms)","trace[659286089] 'applied index is now lower than readState.Index' (duration: 316.646µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:29:25.577247Z","caller":"traceutil/trace.go:171","msg":"trace[2109704879] transaction","detail":"{read_only:false; response_revision:13502; number_of_response:1; }","duration":"251.89307ms","start":"2026-05-20T06:29:25.325327Z","end":"2026-05-20T06:29:25.57722Z","steps":["trace[2109704879] 'process raft request' (duration: 251.558734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:29:25.577501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.637042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:29:25.577639Z","caller":"traceutil/trace.go:171","msg":"trace[640101394] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:13502; }","duration":"200.859496ms","start":"2026-05-20T06:29:25.376748Z","end":"2026-05-20T06:29:25.577607Z","steps":["trace[640101394] 'agreement among raft nodes before linearized reading' (duration: 200.54185ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:29:47.459618Z","caller":"traceutil/trace.go:171","msg":"trace[205395359] transaction","detail":"{read_only:false; response_revision:13809; number_of_response:1; }","duration":"259.287476ms","start":"2026-05-20T06:29:47.200297Z","end":"2026-05-20T06:29:47.459584Z","steps":["trace[205395359] 'process raft request' (duration: 259.076232ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:31:12.741611Z","caller":"traceutil/trace.go:171","msg":"trace[2021447382] linearizableReadLoop","detail":"{readStateIndex:15107; appliedIndex:15106; }","duration":"315.27869ms","start":"2026-05-20T06:31:12.426302Z","end":"2026-05-20T06:31:12.74158Z","steps":["trace[2021447382] 'read index received' (duration: 238.635107ms)","trace[2021447382] 'applied index is now lower than readState.Index' (duration: 76.642253ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:31:12.74196Z","caller":"traceutil/trace.go:171","msg":"trace[1863071165] transaction","detail":"{read_only:false; response_revision:14612; number_of_response:1; }","duration":"316.380891ms","start":"2026-05-20T06:31:12.425545Z","end":"2026-05-20T06:31:12.741926Z","steps":["trace[1863071165] 'process raft request' (duration: 239.379161ms)","trace[1863071165] 'compare' (duration: 76.428019ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:31:12.742169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:31:12.425512Z","time spent":"316.559024ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:31:12.742317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.867782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-20T06:31:12.742381Z","caller":"traceutil/trace.go:171","msg":"trace[2127952056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14612; }","duration":"316.121197ms","start":"2026-05-20T06:31:12.426245Z","end":"2026-05-20T06:31:12.742366Z","steps":["trace[2127952056] 'agreement among raft nodes before linearized reading' (duration: 315.678078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:31:12.742415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:31:12.426219Z","time spent":"316.187548ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T06:31:12.742624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.9108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-20T06:31:12.742685Z","caller":"traceutil/trace.go:171","msg":"trace[1679533206] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14613; }","duration":"231.040273ms","start":"2026-05-20T06:31:12.511631Z","end":"2026-05-20T06:31:12.742671Z","steps":["trace[1679533206] 'agreement among raft nodes before linearized reading' (duration: 230.815018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:31:12.742666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.370322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-72s7k\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-05-20T06:31:12.742757Z","caller":"traceutil/trace.go:171","msg":"trace[364228451] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-72s7k; range_end:; response_count:1; response_revision:14613; }","duration":"296.510105ms","start":"2026-05-20T06:31:12.446222Z","end":"2026-05-20T06:31:12.742732Z","steps":["trace[364228451] 'agreement among raft nodes before linearized reading' (duration: 296.212929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:31:12.742889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.640339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-20T06:31:12.743017Z","caller":"traceutil/trace.go:171","msg":"trace[2076051065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14613; }","duration":"236.810452ms","start":"2026-05-20T06:31:12.506176Z","end":"2026-05-20T06:31:12.742986Z","steps":["trace[2076051065] 'agreement among raft nodes before linearized reading' (duration: 236.466946ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:31:12.742903Z","caller":"traceutil/trace.go:171","msg":"trace[153024173] transaction","detail":"{read_only:false; response_revision:14613; number_of_response:1; }","duration":"250.078973ms","start":"2026-05-20T06:31:12.492776Z","end":"2026-05-20T06:31:12.742855Z","steps":["trace[153024173] 'process raft request' (duration: 249.232416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:31:12.743128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.608388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-20T06:31:12.743262Z","caller":"traceutil/trace.go:171","msg":"trace[1793045241] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14613; }","duration":"217.72451ms","start":"2026-05-20T06:31:12.525479Z","end":"2026-05-20T06:31:12.743203Z","steps":["trace[1793045241] 'agreement among raft nodes before linearized reading' (duration: 217.439725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:31:12.743295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.845852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-72s7k\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-05-20T06:31:12.743379Z","caller":"traceutil/trace.go:171","msg":"trace[544165889] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-72s7k; range_end:; response_count:1; response_revision:14613; }","duration":"295.964264ms","start":"2026-05-20T06:31:12.447393Z","end":"2026-05-20T06:31:12.743358Z","steps":["trace[544165889] 'agreement among raft nodes before linearized reading' (duration: 295.642578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:31:12.743611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.025491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T06:31:12.743665Z","caller":"traceutil/trace.go:171","msg":"trace[823484821] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14613; }","duration":"232.128143ms","start":"2026-05-20T06:31:12.511525Z","end":"2026-05-20T06:31:12.743653Z","steps":["trace[823484821] 'agreement among raft nodes before linearized reading' (duration: 231.296727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:31:12.743814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.428619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-20T06:31:12.743892Z","caller":"traceutil/trace.go:171","msg":"trace[910662907] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14613; }","duration":"232.553301ms","start":"2026-05-20T06:31:12.511311Z","end":"2026-05-20T06:31:12.743864Z","steps":["trace[910662907] 'agreement among raft nodes before linearized reading' (duration: 231.302907ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:32:45.092651Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12863} {"level":"info","ts":"2026-05-20T06:32:45.167863Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12863,"took":"72.464797ms","hash":1226625238,"current-db-size-bytes":56508416,"current-db-size":"56 MB","current-db-size-in-use-bytes":29995008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-20T06:32:45.167915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1226625238,"revision":12863,"compact-revision":10828} {"level":"info","ts":"2026-05-20T06:36:31.858846Z","caller":"traceutil/trace.go:171","msg":"trace[2031734887] transaction","detail":"{read_only:false; response_revision:17040; number_of_response:1; }","duration":"196.815177ms","start":"2026-05-20T06:36:31.661992Z","end":"2026-05-20T06:36:31.858808Z","steps":["trace[2031734887] 'process raft request' (duration: 196.575963ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:36:31.873833Z","caller":"traceutil/trace.go:171","msg":"trace[1947823747] linearizableReadLoop","detail":"{readStateIndex:17614; appliedIndex:17612; }","duration":"169.72297ms","start":"2026-05-20T06:36:31.704074Z","end":"2026-05-20T06:36:31.873797Z","steps":["trace[1947823747] 'read index received' (duration: 154.446653ms)","trace[1947823747] 'applied index is now lower than readState.Index' (duration: 15.274567ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:36:31.873949Z","caller":"traceutil/trace.go:171","msg":"trace[1664622479] transaction","detail":"{read_only:false; response_revision:17041; number_of_response:1; }","duration":"207.873791ms","start":"2026-05-20T06:36:31.66604Z","end":"2026-05-20T06:36:31.873913Z","steps":["trace[1664622479] 'process raft request' (duration: 207.566615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:36:31.874169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.069317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-rhc5s\" ","response":"range_response_count:1 size:20869"} {"level":"info","ts":"2026-05-20T06:36:31.874221Z","caller":"traceutil/trace.go:171","msg":"trace[10793017] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-rhc5s; range_end:; response_count:1; response_revision:17041; }","duration":"170.22552ms","start":"2026-05-20T06:36:31.703983Z","end":"2026-05-20T06:36:31.874209Z","steps":["trace[10793017] 'agreement among raft nodes before linearized reading' (duration: 169.944335ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:36:32.209353Z","caller":"traceutil/trace.go:171","msg":"trace[1679306992] transaction","detail":"{read_only:false; response_revision:17044; number_of_response:1; }","duration":"217.915476ms","start":"2026-05-20T06:36:31.991406Z","end":"2026-05-20T06:36:32.209321Z","steps":["trace[1679306992] 'process raft request' (duration: 204.16957ms)","trace[1679306992] 'compare' (duration: 13.193676ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:36:32.211745Z","caller":"traceutil/trace.go:171","msg":"trace[626081733] transaction","detail":"{read_only:false; response_revision:17045; number_of_response:1; }","duration":"213.97769ms","start":"2026-05-20T06:36:31.997724Z","end":"2026-05-20T06:36:32.211702Z","steps":["trace[626081733] 'process raft request' (duration: 212.076433ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:36:32.637181Z","caller":"traceutil/trace.go:171","msg":"trace[611404695] linearizableReadLoop","detail":"{readStateIndex:17620; appliedIndex:17619; }","duration":"286.5135ms","start":"2026-05-20T06:36:32.35063Z","end":"2026-05-20T06:36:32.637144Z","steps":["trace[611404695] 'read index received' (duration: 265.438031ms)","trace[611404695] 'applied index is now lower than readState.Index' (duration: 21.074169ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:36:32.637608Z","caller":"traceutil/trace.go:171","msg":"trace[1448313448] transaction","detail":"{read_only:false; response_revision:17047; number_of_response:1; }","duration":"351.81607ms","start":"2026-05-20T06:36:32.285715Z","end":"2026-05-20T06:36:32.637531Z","steps":["trace[1448313448] 'process raft request' (duration: 330.506756ms)","trace[1448313448] 'compare' (duration: 19.683013ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:36:32.64564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:36:32.285695Z","time spent":"359.839607ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:36:32.638042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.404471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-20T06:36:32.638093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.40048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-20T06:36:32.638163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.522981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-20T06:36:32.638144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.081834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-05-20T06:36:32.638378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.703532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-20T06:36:32.645749Z","caller":"traceutil/trace.go:171","msg":"trace[379661289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17047; }","duration":"263.240369ms","start":"2026-05-20T06:36:32.382493Z","end":"2026-05-20T06:36:32.645734Z","steps":["trace[379661289] 'agreement among raft nodes before linearized reading' (duration: 255.302604ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:36:32.646262Z","caller":"traceutil/trace.go:171","msg":"trace[433173792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17047; }","duration":"181.689643ms","start":"2026-05-20T06:36:32.464557Z","end":"2026-05-20T06:36:32.646247Z","steps":["trace[433173792] 'agreement among raft nodes before linearized reading' (duration: 173.272039ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:36:32.646578Z","caller":"traceutil/trace.go:171","msg":"trace[1979666918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17047; }","duration":"185.952947ms","start":"2026-05-20T06:36:32.460607Z","end":"2026-05-20T06:36:32.64656Z","steps":["trace[1979666918] 'agreement among raft nodes before linearized reading' (duration: 177.225206ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:36:32.646777Z","caller":"traceutil/trace.go:171","msg":"trace[1480548121] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:17047; }","duration":"296.170079ms","start":"2026-05-20T06:36:32.350587Z","end":"2026-05-20T06:36:32.646757Z","steps":["trace[1480548121] 'agreement among raft nodes before linearized reading' (duration: 287.533641ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:36:32.646938Z","caller":"traceutil/trace.go:171","msg":"trace[85727112] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17047; }","duration":"256.938047ms","start":"2026-05-20T06:36:32.389989Z","end":"2026-05-20T06:36:32.646927Z","steps":["trace[85727112] 'agreement among raft nodes before linearized reading' (duration: 247.89006ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:37:45.10164Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15217} {"level":"info","ts":"2026-05-20T06:37:45.193095Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15217,"took":"87.566891ms","hash":517761783,"current-db-size-bytes":56508416,"current-db-size":"56 MB","current-db-size-in-use-bytes":32919552,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-20T06:37:45.193156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":517761783,"revision":15217,"compact-revision":12863} {"level":"info","ts":"2026-05-20T06:40:34.052456Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2b5783e11defe7e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-20T06:40:34.058769Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-20T06:40:34.058961Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-20T06:42:45.111284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17850} {"level":"info","ts":"2026-05-20T06:42:45.227679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17850,"took":"110.529047ms","hash":2936222764,"current-db-size-bytes":56508416,"current-db-size":"56 MB","current-db-size-in-use-bytes":31830016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-20T06:42:45.22778Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2936222764,"revision":17850,"compact-revision":15217} {"level":"info","ts":"2026-05-20T06:44:02.776948Z","caller":"traceutil/trace.go:171","msg":"trace[391254667] linearizableReadLoop","detail":"{readStateIndex:21464; appliedIndex:21463; }","duration":"154.548907ms","start":"2026-05-20T06:44:02.622362Z","end":"2026-05-20T06:44:02.776911Z","steps":["trace[391254667] 'read index received' (duration: 154.258962ms)","trace[391254667] 'applied index is now lower than readState.Index' (duration: 288.655µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:44:02.777035Z","caller":"traceutil/trace.go:171","msg":"trace[2044764946] transaction","detail":"{read_only:false; response_revision:20776; number_of_response:1; }","duration":"198.083381ms","start":"2026-05-20T06:44:02.578887Z","end":"2026-05-20T06:44:02.77697Z","steps":["trace[2044764946] 'process raft request' (duration: 197.817985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:44:02.777461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.027946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-20T06:44:02.777549Z","caller":"traceutil/trace.go:171","msg":"trace[276846153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20776; }","duration":"155.23219ms","start":"2026-05-20T06:44:02.622296Z","end":"2026-05-20T06:44:02.777528Z","steps":["trace[276846153] 'agreement among raft nodes before linearized reading' (duration: 154.799472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:44:02.998755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.188227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:44:02.998893Z","caller":"traceutil/trace.go:171","msg":"trace[738099350] linearizableReadLoop","detail":"{readStateIndex:21465; appliedIndex:21464; }","duration":"197.53479ms","start":"2026-05-20T06:44:02.801337Z","end":"2026-05-20T06:44:02.998871Z","steps":["trace[738099350] 'read index received' (duration: 87.961146ms)","trace[738099350] 'applied index is now lower than readState.Index' (duration: 109.572024ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:44:02.998937Z","caller":"traceutil/trace.go:171","msg":"trace[247501092] transaction","detail":"{read_only:false; response_revision:20777; number_of_response:1; }","duration":"216.285234ms","start":"2026-05-20T06:44:02.782625Z","end":"2026-05-20T06:44:02.99891Z","steps":["trace[247501092] 'process raft request' (duration: 106.803871ms)","trace[247501092] 'compare' (duration: 108.876311ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:44:02.999223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.889488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-20T06:44:02.999207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.189663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18217"} {"level":"info","ts":"2026-05-20T06:44:02.999296Z","caller":"traceutil/trace.go:171","msg":"trace[1501697071] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20777; }","duration":"197.99774ms","start":"2026-05-20T06:44:02.801284Z","end":"2026-05-20T06:44:02.999282Z","steps":["trace[1501697071] 'agreement among raft nodes before linearized reading' (duration: 197.717564ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:44:02.999329Z","caller":"traceutil/trace.go:171","msg":"trace[1695367477] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:20777; }","duration":"117.356106ms","start":"2026-05-20T06:44:02.881944Z","end":"2026-05-20T06:44:02.9993Z","steps":["trace[1695367477] 'agreement among raft nodes before linearized reading' (duration: 117.06354ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:44:03.224991Z","caller":"traceutil/trace.go:171","msg":"trace[1749409290] transaction","detail":"{read_only:false; response_revision:20779; number_of_response:1; }","duration":"143.579784ms","start":"2026-05-20T06:44:03.081358Z","end":"2026-05-20T06:44:03.224938Z","steps":["trace[1749409290] 'process raft request' (duration: 108.462574ms)","trace[1749409290] 'compare' (duration: 34.873655ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:44:03.517812Z","caller":"traceutil/trace.go:171","msg":"trace[605560266] linearizableReadLoop","detail":"{readStateIndex:21470; appliedIndex:21468; }","duration":"196.196144ms","start":"2026-05-20T06:44:03.321594Z","end":"2026-05-20T06:44:03.51779Z","steps":["trace[605560266] 'read index received' (duration: 153.743921ms)","trace[605560266] 'applied index is now lower than readState.Index' (duration: 42.451033ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:44:03.517923Z","caller":"traceutil/trace.go:171","msg":"trace[1019135945] transaction","detail":"{read_only:false; response_revision:20781; number_of_response:1; }","duration":"289.035175ms","start":"2026-05-20T06:44:03.228879Z","end":"2026-05-20T06:44:03.517914Z","steps":["trace[1019135945] 'process raft request' (duration: 246.544041ms)","trace[1019135945] 'compare' (duration: 41.969074ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:44:03.517942Z","caller":"traceutil/trace.go:171","msg":"trace[579062746] transaction","detail":"{read_only:false; response_revision:20782; number_of_response:1; }","duration":"287.720069ms","start":"2026-05-20T06:44:03.230183Z","end":"2026-05-20T06:44:03.517903Z","steps":["trace[579062746] 'process raft request' (duration: 287.514585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:44:03.518077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.470959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-20T06:44:03.518106Z","caller":"traceutil/trace.go:171","msg":"trace[1814578294] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20782; }","duration":"196.545311ms","start":"2026-05-20T06:44:03.321549Z","end":"2026-05-20T06:44:03.518094Z","steps":["trace[1814578294] 'agreement among raft nodes before linearized reading' (duration: 196.319517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:47:40.931205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.934944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:47:40.931315Z","caller":"traceutil/trace.go:171","msg":"trace[1634360012] linearizableReadLoop","detail":"{readStateIndex:23901; appliedIndex:23900; }","duration":"245.065031ms","start":"2026-05-20T06:47:40.686235Z","end":"2026-05-20T06:47:40.9313Z","steps":["trace[1634360012] 'read index received' (duration: 104.877212ms)","trace[1634360012] 'applied index is now lower than readState.Index' (duration: 140.186649ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:47:40.931513Z","caller":"traceutil/trace.go:171","msg":"trace[1922414973] transaction","detail":"{read_only:false; response_revision:23141; number_of_response:1; }","duration":"248.15527ms","start":"2026-05-20T06:47:40.683347Z","end":"2026-05-20T06:47:40.931502Z","steps":["trace[1922414973] 'process raft request' (duration: 107.843659ms)","trace[1922414973] 'compare' (duration: 139.345052ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:47:40.931709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.471378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-20T06:47:40.931738Z","caller":"traceutil/trace.go:171","msg":"trace[112057537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23141; }","duration":"245.521659ms","start":"2026-05-20T06:47:40.686209Z","end":"2026-05-20T06:47:40.931731Z","steps":["trace[112057537] 'agreement among raft nodes before linearized reading' (duration: 245.418867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:47:40.931881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.850995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-20T06:47:40.931913Z","caller":"traceutil/trace.go:171","msg":"trace[930415344] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23141; }","duration":"149.910146ms","start":"2026-05-20T06:47:40.781995Z","end":"2026-05-20T06:47:40.931905Z","steps":["trace[930415344] 'agreement among raft nodes before linearized reading' (duration: 149.843815ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:47:41.06831Z","caller":"traceutil/trace.go:171","msg":"trace[1291691172] transaction","detail":"{read_only:false; response_revision:23142; number_of_response:1; }","duration":"132.312838ms","start":"2026-05-20T06:47:40.935959Z","end":"2026-05-20T06:47:41.068272Z","steps":["trace[1291691172] 'process raft request' (duration: 131.578074ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:47:41.06843Z","caller":"traceutil/trace.go:171","msg":"trace[481913063] transaction","detail":"{read_only:false; response_revision:23143; number_of_response:1; }","duration":"132.102944ms","start":"2026-05-20T06:47:40.936298Z","end":"2026-05-20T06:47:41.068401Z","steps":["trace[481913063] 'process raft request' (duration: 131.873229ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:47:45.118404Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20350} {"level":"info","ts":"2026-05-20T06:47:45.226667Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20350,"took":"103.092067ms","hash":508446823,"current-db-size-bytes":56508416,"current-db-size":"56 MB","current-db-size-in-use-bytes":41603072,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-20T06:47:45.226755Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":508446823,"revision":20350,"compact-revision":17850} {"level":"info","ts":"2026-05-20T06:49:56.228417Z","caller":"traceutil/trace.go:171","msg":"trace[1437641822] linearizableReadLoop","detail":"{readStateIndex:25315; appliedIndex:25314; }","duration":"222.300498ms","start":"2026-05-20T06:49:56.006089Z","end":"2026-05-20T06:49:56.228389Z","steps":["trace[1437641822] 'read index received' (duration: 222.184846ms)","trace[1437641822] 'applied index is now lower than readState.Index' (duration: 114.592µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:49:56.228593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.473772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:49:56.228557Z","caller":"traceutil/trace.go:171","msg":"trace[1906345175] transaction","detail":"{read_only:false; response_revision:24521; number_of_response:1; }","duration":"263.426092ms","start":"2026-05-20T06:49:55.965071Z","end":"2026-05-20T06:49:56.228497Z","steps":["trace[1906345175] 'process raft request' (duration: 263.113027ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:49:56.228643Z","caller":"traceutil/trace.go:171","msg":"trace[762431655] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24521; }","duration":"222.580024ms","start":"2026-05-20T06:49:56.00605Z","end":"2026-05-20T06:49:56.22863Z","steps":["trace[762431655] 'agreement among raft nodes before linearized reading' (duration: 222.479361ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:49:56.486054Z","caller":"traceutil/trace.go:171","msg":"trace[1656928198] linearizableReadLoop","detail":"{readStateIndex:25316; appliedIndex:25315; }","duration":"257.493449ms","start":"2026-05-20T06:49:56.228498Z","end":"2026-05-20T06:49:56.485992Z","steps":["trace[1656928198] 'read index received' (duration: 204.665603ms)","trace[1656928198] 'applied index is now lower than readState.Index' (duration: 52.826136ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:49:56.486084Z","caller":"traceutil/trace.go:171","msg":"trace[544329612] transaction","detail":"{read_only:false; response_revision:24522; number_of_response:1; }","duration":"413.169017ms","start":"2026-05-20T06:49:56.072866Z","end":"2026-05-20T06:49:56.486035Z","steps":["trace[544329612] 'process raft request' (duration: 360.164766ms)","trace[544329612] 'compare' (duration: 52.103524ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:49:56.48622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.945055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:49:56.486308Z","caller":"traceutil/trace.go:171","msg":"trace[1678357125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24522; }","duration":"368.085448ms","start":"2026-05-20T06:49:56.118202Z","end":"2026-05-20T06:49:56.486288Z","steps":["trace[1678357125] 'agreement among raft nodes before linearized reading' (duration: 367.894974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:49:56.48637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:49:56.118182Z","time spent":"368.16524ms","remote":"127.0.0.1:58704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T06:49:56.48636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:49:56.072835Z","time spent":"413.412942ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:49:57.040975Z","caller":"traceutil/trace.go:171","msg":"trace[1335613335] linearizableReadLoop","detail":"{readStateIndex:25318; appliedIndex:25317; }","duration":"100.650698ms","start":"2026-05-20T06:49:56.940303Z","end":"2026-05-20T06:49:57.040954Z","steps":["trace[1335613335] 'read index received' (duration: 85.675093ms)","trace[1335613335] 'applied index is now lower than readState.Index' (duration: 14.974165ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:49:57.040971Z","caller":"traceutil/trace.go:171","msg":"trace[107373788] transaction","detail":"{read_only:false; response_revision:24524; number_of_response:1; }","duration":"147.194686ms","start":"2026-05-20T06:49:56.893737Z","end":"2026-05-20T06:49:57.040932Z","steps":["trace[107373788] 'process raft request' (duration: 132.285232ms)","trace[107373788] 'compare' (duration: 14.762391ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:49:57.041514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.200189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-20T06:49:57.041648Z","caller":"traceutil/trace.go:171","msg":"trace[1300607057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24525; }","duration":"101.335222ms","start":"2026-05-20T06:49:56.940249Z","end":"2026-05-20T06:49:57.041585Z","steps":["trace[1300607057] 'agreement among raft nodes before linearized reading' (duration: 100.817122ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:50:50.722875Z","caller":"traceutil/trace.go:171","msg":"trace[1862865406] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"108.908127ms","start":"2026-05-20T06:50:50.613911Z","end":"2026-05-20T06:50:50.722819Z","steps":["trace[1862865406] 'process raft request' (duration: 108.687573ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:50:57.45222Z","caller":"traceutil/trace.go:171","msg":"trace[1721366888] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"159.263134ms","start":"2026-05-20T06:50:57.292922Z","end":"2026-05-20T06:50:57.452185Z","steps":["trace[1721366888] 'process raft request' (duration: 159.012949ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:02.957627Z","caller":"traceutil/trace.go:171","msg":"trace[26098720] transaction","detail":"{read_only:false; response_revision:25243; number_of_response:1; }","duration":"138.337439ms","start":"2026-05-20T06:51:02.819257Z","end":"2026-05-20T06:51:02.957595Z","steps":["trace[26098720] 'process raft request' (duration: 138.083695ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:03.328714Z","caller":"traceutil/trace.go:171","msg":"trace[1545719541] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"134.654231ms","start":"2026-05-20T06:51:03.194031Z","end":"2026-05-20T06:51:03.328685Z","steps":["trace[1545719541] 'process raft request' (duration: 134.482928ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:07.486464Z","caller":"traceutil/trace.go:171","msg":"trace[1977969048] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"142.825232ms","start":"2026-05-20T06:51:07.343603Z","end":"2026-05-20T06:51:07.486428Z","steps":["trace[1977969048] 'process raft request' (duration: 142.584187ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:07.626024Z","caller":"traceutil/trace.go:171","msg":"trace[1881524567] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"119.131855ms","start":"2026-05-20T06:51:07.506863Z","end":"2026-05-20T06:51:07.625995Z","steps":["trace[1881524567] 'process raft request' (duration: 118.87676ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:07.91671Z","caller":"traceutil/trace.go:171","msg":"trace[1129593608] linearizableReadLoop","detail":"{readStateIndex:26098; appliedIndex:26095; }","duration":"376.159871ms","start":"2026-05-20T06:51:07.540517Z","end":"2026-05-20T06:51:07.916677Z","steps":["trace[1129593608] 'read index received' (duration: 85.352722ms)","trace[1129593608] 'applied index is now lower than readState.Index' (duration: 290.805989ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:51:07.916909Z","caller":"traceutil/trace.go:171","msg":"trace[63845666] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"321.062146ms","start":"2026-05-20T06:51:07.595795Z","end":"2026-05-20T06:51:07.916857Z","steps":["trace[63845666] 'process raft request' (duration: 320.825362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:07.917041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.490777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-20T06:51:07.917094Z","caller":"traceutil/trace.go:171","msg":"trace[1840092189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25285; }","duration":"376.629509ms","start":"2026-05-20T06:51:07.540449Z","end":"2026-05-20T06:51:07.917079Z","steps":["trace[1840092189] 'agreement among raft nodes before linearized reading' (duration: 376.393695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:07.917142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:07.540425Z","time spent":"376.708551ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T06:51:07.917149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:07.595777Z","time spent":"321.245429ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:07.917149Z","caller":"traceutil/trace.go:171","msg":"trace[199855782] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"387.337047ms","start":"2026-05-20T06:51:07.529765Z","end":"2026-05-20T06:51:07.917102Z","steps":["trace[199855782] 'process raft request' (duration: 386.697175ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:07.917166Z","caller":"traceutil/trace.go:171","msg":"trace[115329227] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"387.49078ms","start":"2026-05-20T06:51:07.529636Z","end":"2026-05-20T06:51:07.917127Z","steps":["trace[115329227] 'process raft request' (duration: 382.276374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:07.917365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:07.529749Z","time spent":"387.50437ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:07.917385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:07.529603Z","time spent":"387.661702ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:07.938656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.946808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-05-20T06:51:07.938747Z","caller":"traceutil/trace.go:171","msg":"trace[136500046] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25286; }","duration":"344.111431ms","start":"2026-05-20T06:51:07.59461Z","end":"2026-05-20T06:51:07.938721Z","steps":["trace[136500046] 'agreement among raft nodes before linearized reading' (duration: 343.711884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:07.938802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:07.59458Z","time spent":"344.208902ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-20T06:51:07.938831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.557558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-20T06:51:07.938954Z","caller":"traceutil/trace.go:171","msg":"trace[119086802] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25286; }","duration":"332.715321ms","start":"2026-05-20T06:51:07.606211Z","end":"2026-05-20T06:51:07.938926Z","steps":["trace[119086802] 'agreement among raft nodes before linearized reading' (duration: 332.230732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:07.938962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.316371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-20T06:51:07.939014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:07.60613Z","time spent":"332.872703ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-20T06:51:07.939072Z","caller":"traceutil/trace.go:171","msg":"trace[62462835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25286; }","duration":"105.482454ms","start":"2026-05-20T06:51:07.833565Z","end":"2026-05-20T06:51:07.939047Z","steps":["trace[62462835] 'agreement among raft nodes before linearized reading' (duration: 104.830092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:08.23719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.588225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:51:08.237313Z","caller":"traceutil/trace.go:171","msg":"trace[2115400364] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"315.468362ms","start":"2026-05-20T06:51:07.921822Z","end":"2026-05-20T06:51:08.23729Z","steps":["trace[2115400364] 'process raft request' (duration: 139.672894ms)","trace[2115400364] 'compare' (duration: 175.368011ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:51:08.237384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:07.921798Z","time spent":"315.554213ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:08.245535Z","caller":"traceutil/trace.go:171","msg":"trace[599411202] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"301.299792ms","start":"2026-05-20T06:51:07.944207Z","end":"2026-05-20T06:51:08.245507Z","steps":["trace[599411202] 'process raft request' (duration: 301.076008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:08.245698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:07.94418Z","time spent":"301.433864ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:08.245975Z","caller":"traceutil/trace.go:171","msg":"trace[2002668328] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"301.307182ms","start":"2026-05-20T06:51:07.94463Z","end":"2026-05-20T06:51:08.245937Z","steps":["trace[2002668328] 'process raft request' (duration: 300.829383ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:08.246095Z","caller":"traceutil/trace.go:171","msg":"trace[1444054451] linearizableReadLoop","detail":"{readStateIndex:26104; appliedIndex:26101; }","duration":"176.722927ms","start":"2026-05-20T06:51:08.069356Z","end":"2026-05-20T06:51:08.246079Z","steps":["trace[1444054451] 'read index received' (duration: 175.895581ms)","trace[1444054451] 'applied index is now lower than readState.Index' (duration: 825.236µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:51:08.246182Z","caller":"traceutil/trace.go:171","msg":"trace[1560107614] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"301.502796ms","start":"2026-05-20T06:51:07.944643Z","end":"2026-05-20T06:51:08.246146Z","steps":["trace[1560107614] 'process raft request' (duration: 301.132069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:08.246434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:07.944609Z","time spent":"301.707639ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:08.246448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.089425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-20T06:51:08.246532Z","caller":"traceutil/trace.go:171","msg":"trace[1578711545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25290; }","duration":"177.182366ms","start":"2026-05-20T06:51:08.069307Z","end":"2026-05-20T06:51:08.246489Z","steps":["trace[1578711545] 'agreement among raft nodes before linearized reading' (duration: 176.88468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:08.246773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:07.944589Z","time spent":"301.467936ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:08.532718Z","caller":"traceutil/trace.go:171","msg":"trace[205634389] linearizableReadLoop","detail":"{readStateIndex:26108; appliedIndex:26106; }","duration":"164.108254ms","start":"2026-05-20T06:51:08.368577Z","end":"2026-05-20T06:51:08.532685Z","steps":["trace[205634389] 'read index received' (duration: 22.334741ms)","trace[205634389] 'applied index is now lower than readState.Index' (duration: 141.772323ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:51:08.532842Z","caller":"traceutil/trace.go:171","msg":"trace[2019846475] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"168.084168ms","start":"2026-05-20T06:51:08.364747Z","end":"2026-05-20T06:51:08.532831Z","steps":["trace[2019846475] 'process raft request' (duration: 165.470479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:08.533133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.535633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-20T06:51:08.533178Z","caller":"traceutil/trace.go:171","msg":"trace[995069698] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25293; }","duration":"164.609874ms","start":"2026-05-20T06:51:08.368557Z","end":"2026-05-20T06:51:08.533167Z","steps":["trace[995069698] 'agreement among raft nodes before linearized reading' (duration: 164.463771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:08.533465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.117632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-20T06:51:08.533636Z","caller":"traceutil/trace.go:171","msg":"trace[360406084] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25293; }","duration":"140.265115ms","start":"2026-05-20T06:51:08.393241Z","end":"2026-05-20T06:51:08.533506Z","steps":["trace[360406084] 'agreement among raft nodes before linearized reading' (duration: 139.944859ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:09.6362Z","caller":"traceutil/trace.go:171","msg":"trace[536643616] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"143.572745ms","start":"2026-05-20T06:51:09.492608Z","end":"2026-05-20T06:51:09.636181Z","steps":["trace[536643616] 'process raft request' (duration: 143.432523ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:10.541189Z","caller":"traceutil/trace.go:171","msg":"trace[344590058] linearizableReadLoop","detail":"{readStateIndex:26116; appliedIndex:26115; }","duration":"290.387081ms","start":"2026-05-20T06:51:10.250776Z","end":"2026-05-20T06:51:10.541163Z","steps":["trace[344590058] 'read index received' (duration: 290.277279ms)","trace[344590058] 'applied index is now lower than readState.Index' (duration: 108.232µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:51:10.541245Z","caller":"traceutil/trace.go:171","msg":"trace[1061302244] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"293.332875ms","start":"2026-05-20T06:51:10.247873Z","end":"2026-05-20T06:51:10.541206Z","steps":["trace[1061302244] 'process raft request' (duration: 293.07503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:10.541449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.635055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-20T06:51:10.541487Z","caller":"traceutil/trace.go:171","msg":"trace[1390924485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25301; }","duration":"290.743078ms","start":"2026-05-20T06:51:10.250732Z","end":"2026-05-20T06:51:10.541475Z","steps":["trace[1390924485] 'agreement among raft nodes before linearized reading' (duration: 290.557534ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:10.794451Z","caller":"traceutil/trace.go:171","msg":"trace[1777947633] linearizableReadLoop","detail":"{readStateIndex:26117; appliedIndex:26116; }","duration":"253.132024ms","start":"2026-05-20T06:51:10.541258Z","end":"2026-05-20T06:51:10.79439Z","steps":["trace[1777947633] 'read index received' (duration: 242.599579ms)","trace[1777947633] 'applied index is now lower than readState.Index' (duration: 10.530095ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:51:10.794582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.001297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-20T06:51:10.794541Z","caller":"traceutil/trace.go:171","msg":"trace[140853012] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"521.666602ms","start":"2026-05-20T06:51:10.272815Z","end":"2026-05-20T06:51:10.794482Z","steps":["trace[140853012] 'process raft request' (duration: 511.055676ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:10.794652Z","caller":"traceutil/trace.go:171","msg":"trace[321204941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25302; }","duration":"256.137679ms","start":"2026-05-20T06:51:10.538498Z","end":"2026-05-20T06:51:10.794635Z","steps":["trace[321204941] 'agreement among raft nodes before linearized reading' (duration: 255.902595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:10.794745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.066033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-20T06:51:10.794849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.679418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-05-20T06:51:10.794875Z","caller":"traceutil/trace.go:171","msg":"trace[379510971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25302; }","duration":"526.276267ms","start":"2026-05-20T06:51:10.26857Z","end":"2026-05-20T06:51:10.794846Z","steps":["trace[379510971] 'agreement among raft nodes before linearized reading' (duration: 525.854279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:10.794858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:10.272796Z","time spent":"521.880736ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:10.794927Z","caller":"traceutil/trace.go:171","msg":"trace[1260856822] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25302; }","duration":"543.81461ms","start":"2026-05-20T06:51:10.251095Z","end":"2026-05-20T06:51:10.79491Z","steps":["trace[1260856822] 'agreement among raft nodes before linearized reading' (duration: 543.506655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:10.794936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:10.268525Z","time spent":"526.398979ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T06:51:10.794964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:10.251069Z","time spent":"543.886352ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-20T06:51:10.794758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.683879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-20T06:51:10.79506Z","caller":"traceutil/trace.go:171","msg":"trace[1873571503] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25302; }","duration":"543.032125ms","start":"2026-05-20T06:51:10.251975Z","end":"2026-05-20T06:51:10.795007Z","steps":["trace[1873571503] 'agreement among raft nodes before linearized reading' (duration: 542.510075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:10.795169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:10.251964Z","time spent":"543.164548ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-20T06:51:10.80482Z","caller":"traceutil/trace.go:171","msg":"trace[1405473086] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"257.663317ms","start":"2026-05-20T06:51:10.547131Z","end":"2026-05-20T06:51:10.804794Z","steps":["trace[1405473086] 'process raft request' (duration: 257.445364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:10.804918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.217074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-20T06:51:10.805036Z","caller":"traceutil/trace.go:171","msg":"trace[1995585484] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25303; }","duration":"241.363866ms","start":"2026-05-20T06:51:10.563651Z","end":"2026-05-20T06:51:10.805015Z","steps":["trace[1995585484] 'agreement among raft nodes before linearized reading' (duration: 241.072541ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:11.111576Z","caller":"traceutil/trace.go:171","msg":"trace[1447667689] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"311.310456ms","start":"2026-05-20T06:51:10.800229Z","end":"2026-05-20T06:51:11.11154Z","steps":["trace[1447667689] 'process raft request' (duration: 310.040703ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:11.111625Z","caller":"traceutil/trace.go:171","msg":"trace[308790846] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"311.143403ms","start":"2026-05-20T06:51:10.800438Z","end":"2026-05-20T06:51:11.111582Z","steps":["trace[308790846] 'process raft request' (duration: 310.735276ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:11.111702Z","caller":"traceutil/trace.go:171","msg":"trace[107351461] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"311.205815ms","start":"2026-05-20T06:51:10.800463Z","end":"2026-05-20T06:51:11.111669Z","steps":["trace[107351461] 'process raft request' (duration: 310.824878ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:11.111813Z","caller":"traceutil/trace.go:171","msg":"trace[269585895] transaction","detail":"{read_only:false; response_revision:25307; number_of_response:1; }","duration":"310.439069ms","start":"2026-05-20T06:51:10.801332Z","end":"2026-05-20T06:51:11.111771Z","steps":["trace[269585895] 'process raft request' (duration: 310.285227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:11.111775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:10.800204Z","time spent":"311.458639ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:11.11194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:10.800436Z","time spent":"311.384808ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:11.112086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:10.80131Z","time spent":"310.611303ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:11.112397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:10.80041Z","time spent":"311.896078ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:11.12889Z","caller":"traceutil/trace.go:171","msg":"trace[2083236472] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"319.11544ms","start":"2026-05-20T06:51:10.809744Z","end":"2026-05-20T06:51:11.128859Z","steps":["trace[2083236472] 'process raft request' (duration: 318.860216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:11.129085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:10.809727Z","time spent":"319.255002ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:11.129214Z","caller":"traceutil/trace.go:171","msg":"trace[230570178] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"206.185019ms","start":"2026-05-20T06:51:10.923005Z","end":"2026-05-20T06:51:11.12919Z","steps":["trace[230570178] 'process raft request' (duration: 205.787582ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:11.773536Z","caller":"traceutil/trace.go:171","msg":"trace[1520139078] transaction","detail":"{read_only:false; response_revision:25311; number_of_response:1; }","duration":"131.536554ms","start":"2026-05-20T06:51:11.641981Z","end":"2026-05-20T06:51:11.773517Z","steps":["trace[1520139078] 'process raft request' (duration: 131.349821ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:12.042649Z","caller":"traceutil/trace.go:171","msg":"trace[1608633766] linearizableReadLoop","detail":"{readStateIndex:26127; appliedIndex:26125; }","duration":"299.973437ms","start":"2026-05-20T06:51:11.742638Z","end":"2026-05-20T06:51:12.042611Z","steps":["trace[1608633766] 'read index received' (duration: 30.682005ms)","trace[1608633766] 'applied index is now lower than readState.Index' (duration: 269.289082ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:51:12.042759Z","caller":"traceutil/trace.go:171","msg":"trace[1687966193] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"366.168507ms","start":"2026-05-20T06:51:11.676477Z","end":"2026-05-20T06:51:12.042646Z","steps":["trace[1687966193] 'process raft request' (duration: 365.809741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:12.042917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.257592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:186"} {"level":"info","ts":"2026-05-20T06:51:12.043109Z","caller":"traceutil/trace.go:171","msg":"trace[609295837] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:25312; }","duration":"300.496887ms","start":"2026-05-20T06:51:11.742592Z","end":"2026-05-20T06:51:12.043089Z","steps":["trace[609295837] 'agreement among raft nodes before linearized reading' (duration: 300.175511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:12.043165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:11.742565Z","time spent":"300.587408ms","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-05-20T06:51:12.043194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:11.676437Z","time spent":"366.561644ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:12.062655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.596888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:51:12.062775Z","caller":"traceutil/trace.go:171","msg":"trace[342242910] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25315; }","duration":"282.72454ms","start":"2026-05-20T06:51:11.780023Z","end":"2026-05-20T06:51:12.062748Z","steps":["trace[342242910] 'agreement among raft nodes before linearized reading' (duration: 281.971777ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:12.677192Z","caller":"traceutil/trace.go:171","msg":"trace[530631608] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"125.083734ms","start":"2026-05-20T06:51:12.552074Z","end":"2026-05-20T06:51:12.677157Z","steps":["trace[530631608] 'process raft request' (duration: 124.86794ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:13.13877Z","caller":"traceutil/trace.go:171","msg":"trace[822353212] linearizableReadLoop","detail":"{readStateIndex:26132; appliedIndex:26131; }","duration":"329.192435ms","start":"2026-05-20T06:51:12.809554Z","end":"2026-05-20T06:51:13.138746Z","steps":["trace[822353212] 'read index received' (duration: 325.904815ms)","trace[822353212] 'applied index is now lower than readState.Index' (duration: 3.28647ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:51:13.138856Z","caller":"traceutil/trace.go:171","msg":"trace[837269292] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"355.827866ms","start":"2026-05-20T06:51:12.782996Z","end":"2026-05-20T06:51:13.138824Z","steps":["trace[837269292] 'process raft request' (duration: 352.506855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:13.139015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:12.782967Z","time spent":"355.955659ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:13.139008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.42698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-20T06:51:13.139111Z","caller":"traceutil/trace.go:171","msg":"trace[636851874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25317; }","duration":"329.580512ms","start":"2026-05-20T06:51:12.809514Z","end":"2026-05-20T06:51:13.139095Z","steps":["trace[636851874] 'agreement among raft nodes before linearized reading' (duration: 329.355158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:13.139145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:12.809491Z","time spent":"329.642204ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T06:51:13.162886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.881867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:51:13.162986Z","caller":"traceutil/trace.go:171","msg":"trace[2055268307] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:25318; }","duration":"229.065951ms","start":"2026-05-20T06:51:12.933895Z","end":"2026-05-20T06:51:13.162961Z","steps":["trace[2055268307] 'agreement among raft nodes before linearized reading' (duration: 228.912369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:13.163245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.380457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:51:13.163367Z","caller":"traceutil/trace.go:171","msg":"trace[1953962021] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25319; }","duration":"331.57231ms","start":"2026-05-20T06:51:12.831772Z","end":"2026-05-20T06:51:13.163344Z","steps":["trace[1953962021] 'agreement among raft nodes before linearized reading' (duration: 331.344006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:13.163454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:12.831744Z","time spent":"331.699192ms","remote":"127.0.0.1:37468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"info","ts":"2026-05-20T06:51:13.299725Z","caller":"traceutil/trace.go:171","msg":"trace[1688674430] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"126.890809ms","start":"2026-05-20T06:51:13.172779Z","end":"2026-05-20T06:51:13.29967Z","steps":["trace[1688674430] 'process raft request' (duration: 126.746725ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:13.299741Z","caller":"traceutil/trace.go:171","msg":"trace[1530872741] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"126.98107ms","start":"2026-05-20T06:51:13.172707Z","end":"2026-05-20T06:51:13.299688Z","steps":["trace[1530872741] 'process raft request' (duration: 126.50411ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:13.300208Z","caller":"traceutil/trace.go:171","msg":"trace[589394613] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"127.345927ms","start":"2026-05-20T06:51:13.17285Z","end":"2026-05-20T06:51:13.300196Z","steps":["trace[589394613] 'process raft request' (duration: 127.180304ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:13.3003Z","caller":"traceutil/trace.go:171","msg":"trace[1321000825] transaction","detail":"{read_only:false; response_revision:25323; number_of_response:1; }","duration":"127.442028ms","start":"2026-05-20T06:51:13.172818Z","end":"2026-05-20T06:51:13.30026Z","steps":["trace[1321000825] 'process raft request' (duration: 126.784335ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:22.896203Z","caller":"traceutil/trace.go:171","msg":"trace[833908794] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"166.278394ms","start":"2026-05-20T06:51:22.729899Z","end":"2026-05-20T06:51:22.896177Z","steps":["trace[833908794] 'process raft request' (duration: 166.112301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:23.706294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338268747505533217,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T06:51:24.005445Z","caller":"traceutil/trace.go:171","msg":"trace[8027947] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"805.41229ms","start":"2026-05-20T06:51:23.199957Z","end":"2026-05-20T06:51:24.00537Z","steps":["trace[8027947] 'process raft request' (duration: 804.982562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:24.005675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:23.199931Z","time spent":"805.618384ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:24.298388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.731295ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-20T06:51:24.300351Z","caller":"traceutil/trace.go:171","msg":"trace[694610473] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"476.918608ms","start":"2026-05-20T06:51:23.823394Z","end":"2026-05-20T06:51:24.300313Z","steps":["trace[694610473] 'process raft request' (duration: 475.094785ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:24.30038Z","caller":"traceutil/trace.go:171","msg":"trace[205379120] linearizableReadLoop","detail":"{readStateIndex:26210; appliedIndex:26208; }","duration":"1.094329004s","start":"2026-05-20T06:51:23.20602Z","end":"2026-05-20T06:51:24.300349Z","steps":["trace[205379120] 'read index received' (duration: 799.167616ms)","trace[205379120] 'applied index is now lower than readState.Index' (duration: 295.160418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:51:24.300608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:23.82336Z","time spent":"477.054611ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:24.415935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209894544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-20T06:51:24.416029Z","caller":"traceutil/trace.go:171","msg":"trace[1532749406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25393; }","duration":"1.210039087s","start":"2026-05-20T06:51:23.205966Z","end":"2026-05-20T06:51:24.416005Z","steps":["trace[1532749406] 'agreement among raft nodes before linearized reading' (duration: 1.094471077s)","trace[1532749406] 'range keys from in-memory index tree' (duration: 115.299995ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:51:24.416066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:23.205949Z","time spent":"1.210109348s","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T06:51:24.416516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.11395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:51:24.416579Z","caller":"traceutil/trace.go:171","msg":"trace[1792884334] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"312.210713ms","start":"2026-05-20T06:51:24.104356Z","end":"2026-05-20T06:51:24.416567Z","steps":["trace[1792884334] 'process raft request' (duration: 195.982161ms)","trace[1792884334] 'compare' (duration: 115.170843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:51:24.416618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.104334Z","time spent":"312.263315ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:24.935472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.066946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:51:24.935787Z","caller":"traceutil/trace.go:171","msg":"trace[848609613] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"511.953314ms","start":"2026-05-20T06:51:24.42381Z","end":"2026-05-20T06:51:24.935764Z","steps":["trace[848609613] 'process raft request' (duration: 511.893643ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:24.935784Z","caller":"traceutil/trace.go:171","msg":"trace[560501013] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"830.859079ms","start":"2026-05-20T06:51:24.104873Z","end":"2026-05-20T06:51:24.935732Z","steps":["trace[560501013] 'process raft request' (duration: 554.415576ms)","trace[560501013] 'compare' (duration: 275.899063ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:51:24.935863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.423787Z","time spent":"512.039195ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:24.935854Z","caller":"traceutil/trace.go:171","msg":"trace[229341135] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"813.129303ms","start":"2026-05-20T06:51:24.122689Z","end":"2026-05-20T06:51:24.935818Z","steps":["trace[229341135] 'process raft request' (duration: 812.96136ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:24.935891Z","caller":"traceutil/trace.go:171","msg":"trace[1160986230] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"815.418595ms","start":"2026-05-20T06:51:24.120448Z","end":"2026-05-20T06:51:24.935866Z","steps":["trace[1160986230] 'process raft request' (duration: 815.14585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:24.935962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.104839Z","time spent":"831.019043ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:24.935994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.120434Z","time spent":"815.502497ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:24.936018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.122675Z","time spent":"813.253665ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:24.935947Z","caller":"traceutil/trace.go:171","msg":"trace[1599807336] linearizableReadLoop","detail":"{readStateIndex:26215; appliedIndex:26212; }","duration":"635.468709ms","start":"2026-05-20T06:51:24.300432Z","end":"2026-05-20T06:51:24.9359Z","steps":["trace[1599807336] 'read index received' (duration: 358.944805ms)","trace[1599807336] 'applied index is now lower than readState.Index' (duration: 276.519114ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:51:24.936126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305881412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-20T06:51:24.936162Z","caller":"traceutil/trace.go:171","msg":"trace[170463562] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:25398; }","duration":"1.305954834s","start":"2026-05-20T06:51:23.630194Z","end":"2026-05-20T06:51:24.936149Z","steps":["trace[170463562] 'agreement among raft nodes before linearized reading' (duration: 1.305804191s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:24.936194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:23.630169Z","time spent":"1.306017025s","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":125,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:51:24.936291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.55376963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-20T06:51:24.936279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.552337004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-20T06:51:24.936334Z","caller":"traceutil/trace.go:171","msg":"trace[1687294556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25398; }","duration":"1.553862872s","start":"2026-05-20T06:51:23.382458Z","end":"2026-05-20T06:51:24.936321Z","steps":["trace[1687294556] 'agreement among raft nodes before linearized reading' (duration: 1.553697639s)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:24.93635Z","caller":"traceutil/trace.go:171","msg":"trace[1290314761] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25398; }","duration":"1.552416165s","start":"2026-05-20T06:51:23.383915Z","end":"2026-05-20T06:51:24.936331Z","steps":["trace[1290314761] 'agreement among raft nodes before linearized reading' (duration: 1.552243932s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:24.93637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:23.382433Z","time spent":"1.553929173s","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T06:51:24.93639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.261719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-20T06:51:24.936424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.552624519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-05-20T06:51:24.936425Z","caller":"traceutil/trace.go:171","msg":"trace[47026786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25398; }","duration":"785.320981ms","start":"2026-05-20T06:51:24.151094Z","end":"2026-05-20T06:51:24.936415Z","steps":["trace[47026786] 'agreement among raft nodes before linearized reading' (duration: 785.26456ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:24.936448Z","caller":"traceutil/trace.go:171","msg":"trace[1413362159] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25398; }","duration":"1.55266594s","start":"2026-05-20T06:51:23.383775Z","end":"2026-05-20T06:51:24.936441Z","steps":["trace[1413362159] 'agreement among raft nodes before linearized reading' (duration: 1.552616359s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:24.936436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.564905895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-20T06:51:24.936465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:23.383765Z","time spent":"1.55269752s","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-20T06:51:24.936476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.051984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-20T06:51:24.936377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:23.383872Z","time spent":"1.552500487s","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-20T06:51:24.936498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198994303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:51:24.936505Z","caller":"traceutil/trace.go:171","msg":"trace[1774028087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25398; }","duration":"1.565028778s","start":"2026-05-20T06:51:23.371451Z","end":"2026-05-20T06:51:24.93648Z","steps":["trace[1774028087] 'agreement among raft nodes before linearized reading' (duration: 1.564878045s)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:24.93652Z","caller":"traceutil/trace.go:171","msg":"trace[829641849] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:25398; }","duration":"112.167647ms","start":"2026-05-20T06:51:24.824337Z","end":"2026-05-20T06:51:24.936505Z","steps":["trace[829641849] 'agreement among raft nodes before linearized reading' (duration: 112.104725ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:24.936546Z","caller":"traceutil/trace.go:171","msg":"trace[2052947094] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25398; }","duration":"1.199091376s","start":"2026-05-20T06:51:23.737439Z","end":"2026-05-20T06:51:24.936531Z","steps":["trace[2052947094] 'agreement among raft nodes before linearized reading' (duration: 1.199021573s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:24.936562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:23.37142Z","time spent":"1.56512988s","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T06:51:24.936579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:23.737386Z","time spent":"1.199186147s","remote":"127.0.0.1:51332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-20T06:51:24.936609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593646345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-20T06:51:24.936286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632812015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-20T06:51:24.936645Z","caller":"traceutil/trace.go:171","msg":"trace[1113464858] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25398; }","duration":"1.593724747s","start":"2026-05-20T06:51:23.342909Z","end":"2026-05-20T06:51:24.936634Z","steps":["trace[1113464858] 'agreement among raft nodes before linearized reading' (duration: 1.593669116s)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:24.936656Z","caller":"traceutil/trace.go:171","msg":"trace[1897364407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25398; }","duration":"1.633256995s","start":"2026-05-20T06:51:23.303387Z","end":"2026-05-20T06:51:24.936644Z","steps":["trace[1897364407] 'agreement among raft nodes before linearized reading' (duration: 1.632645333s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:24.936685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.01289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-20T06:51:24.936683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:23.34288Z","time spent":"1.593794548s","remote":"127.0.0.1:37522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:51:24.936699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:23.30336Z","time spent":"1.633324616s","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T06:51:24.936503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214359676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:51:24.936707Z","caller":"traceutil/trace.go:171","msg":"trace[1620200897] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:25398; }","duration":"826.066182ms","start":"2026-05-20T06:51:24.110633Z","end":"2026-05-20T06:51:24.9367Z","steps":["trace[1620200897] 'agreement among raft nodes before linearized reading' (duration: 826.020411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:24.936723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.431344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-20T06:51:24.936739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.110621Z","time spent":"826.113552ms","remote":"127.0.0.1:51332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":10,"response size":31,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"info","ts":"2026-05-20T06:51:24.936744Z","caller":"traceutil/trace.go:171","msg":"trace[1246237059] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25398; }","duration":"1.214634391s","start":"2026-05-20T06:51:23.722097Z","end":"2026-05-20T06:51:24.936732Z","steps":["trace[1246237059] 'agreement among raft nodes before linearized reading' (duration: 1.214377266s)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:24.936759Z","caller":"traceutil/trace.go:171","msg":"trace[735556351] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25398; }","duration":"143.516496ms","start":"2026-05-20T06:51:24.793233Z","end":"2026-05-20T06:51:24.93675Z","steps":["trace[735556351] 'agreement among raft nodes before linearized reading' (duration: 143.458575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:24.936789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:23.722074Z","time spent":"1.214701853s","remote":"127.0.0.1:37546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"warn","ts":"2026-05-20T06:51:24.936295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.700235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-20T06:51:24.936833Z","caller":"traceutil/trace.go:171","msg":"trace[1895200798] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25398; }","duration":"361.294327ms","start":"2026-05-20T06:51:24.575528Z","end":"2026-05-20T06:51:24.936822Z","steps":["trace[1895200798] 'agreement among raft nodes before linearized reading' (duration: 360.624534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:24.936864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.575504Z","time spent":"361.351559ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-20T06:51:24.936453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.15108Z","time spent":"785.367921ms","remote":"127.0.0.1:58704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-20T06:51:25.348687Z","caller":"traceutil/trace.go:171","msg":"trace[393805858] linearizableReadLoop","detail":"{readStateIndex:26222; appliedIndex:26216; }","duration":"402.001008ms","start":"2026-05-20T06:51:24.946666Z","end":"2026-05-20T06:51:25.348667Z","steps":["trace[393805858] 'read index received' (duration: 48.30886ms)","trace[393805858] 'applied index is now lower than readState.Index' (duration: 353.691388ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:51:25.348903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.217332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18255"} {"level":"info","ts":"2026-05-20T06:51:25.348942Z","caller":"traceutil/trace.go:171","msg":"trace[1248020374] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25404; }","duration":"402.290044ms","start":"2026-05-20T06:51:24.946638Z","end":"2026-05-20T06:51:25.348928Z","steps":["trace[1248020374] 'agreement among raft nodes before linearized reading' (duration: 402.128481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:25.348974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.946631Z","time spent":"402.336224ms","remote":"127.0.0.1:58896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18278,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-20T06:51:25.349261Z","caller":"traceutil/trace.go:171","msg":"trace[1256145655] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"403.606058ms","start":"2026-05-20T06:51:24.945646Z","end":"2026-05-20T06:51:25.349252Z","steps":["trace[1256145655] 'process raft request' (duration: 402.731912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:25.349342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.945624Z","time spent":"403.663188ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:25.349474Z","caller":"traceutil/trace.go:171","msg":"trace[1082485211] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"403.79912ms","start":"2026-05-20T06:51:24.945668Z","end":"2026-05-20T06:51:25.349467Z","steps":["trace[1082485211] 'process raft request' (duration: 402.859323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:25.349516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.945656Z","time spent":"403.837201ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:25.349607Z","caller":"traceutil/trace.go:171","msg":"trace[770563510] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"403.891892ms","start":"2026-05-20T06:51:24.945709Z","end":"2026-05-20T06:51:25.349601Z","steps":["trace[770563510] 'process raft request' (duration: 402.853043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:25.349643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.945704Z","time spent":"403.920903ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:25.349727Z","caller":"traceutil/trace.go:171","msg":"trace[1261348239] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"403.944743ms","start":"2026-05-20T06:51:24.945775Z","end":"2026-05-20T06:51:25.34972Z","steps":["trace[1261348239] 'process raft request' (duration: 402.823793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:25.349758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.945768Z","time spent":"403.972833ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:51:25.349843Z","caller":"traceutil/trace.go:171","msg":"trace[237618709] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"403.944533ms","start":"2026-05-20T06:51:24.945892Z","end":"2026-05-20T06:51:25.349837Z","steps":["trace[237618709] 'process raft request' (duration: 402.730271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:25.349887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:51:24.945884Z","time spent":"403.977613ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:51:29.676921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.170075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:51:29.677064Z","caller":"traceutil/trace.go:171","msg":"trace[718452908] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25492; }","duration":"164.394689ms","start":"2026-05-20T06:51:29.51264Z","end":"2026-05-20T06:51:29.677034Z","steps":["trace[718452908] 'count revisions from in-memory index tree' (duration: 164.064614ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:33.12708Z","caller":"traceutil/trace.go:171","msg":"trace[479781384] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"153.502988ms","start":"2026-05-20T06:51:32.973554Z","end":"2026-05-20T06:51:33.127057Z","steps":["trace[479781384] 'process raft request' (duration: 153.339506ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:33.12787Z","caller":"traceutil/trace.go:171","msg":"trace[2118979485] linearizableReadLoop","detail":"{readStateIndex:26359; appliedIndex:26356; }","duration":"149.009127ms","start":"2026-05-20T06:51:32.978839Z","end":"2026-05-20T06:51:33.127848Z","steps":["trace[2118979485] 'read index received' (duration: 148.075879ms)","trace[2118979485] 'applied index is now lower than readState.Index' (duration: 932.338µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:51:33.127937Z","caller":"traceutil/trace.go:171","msg":"trace[1576303817] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"154.174971ms","start":"2026-05-20T06:51:32.97374Z","end":"2026-05-20T06:51:33.127915Z","steps":["trace[1576303817] 'process raft request' (duration: 153.944747ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:51:33.127971Z","caller":"traceutil/trace.go:171","msg":"trace[1098852367] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"153.765874ms","start":"2026-05-20T06:51:32.974182Z","end":"2026-05-20T06:51:33.127948Z","steps":["trace[1098852367] 'process raft request' (duration: 153.628151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:51:33.128089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.242351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-20T06:51:33.128146Z","caller":"traceutil/trace.go:171","msg":"trace[1078578409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25539; }","duration":"149.346213ms","start":"2026-05-20T06:51:32.978785Z","end":"2026-05-20T06:51:33.128131Z","steps":["trace[1078578409] 'agreement among raft nodes before linearized reading' (duration: 149.16637ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:03.934519Z","caller":"traceutil/trace.go:171","msg":"trace[1280425979] transaction","detail":"{read_only:false; response_revision:25785; number_of_response:1; }","duration":"338.037905ms","start":"2026-05-20T06:52:03.596454Z","end":"2026-05-20T06:52:03.934492Z","steps":["trace[1280425979] 'process raft request' (duration: 337.013356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:03.93469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:03.596442Z","time spent":"338.162857ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:52:03.934985Z","caller":"traceutil/trace.go:171","msg":"trace[1308722166] transaction","detail":"{read_only:false; response_revision:25787; number_of_response:1; }","duration":"337.19307ms","start":"2026-05-20T06:52:03.597732Z","end":"2026-05-20T06:52:03.934925Z","steps":["trace[1308722166] 'process raft request' (duration: 335.965337ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:03.93505Z","caller":"traceutil/trace.go:171","msg":"trace[1683140614] transaction","detail":"{read_only:false; response_revision:25786; number_of_response:1; }","duration":"337.714239ms","start":"2026-05-20T06:52:03.597323Z","end":"2026-05-20T06:52:03.935037Z","steps":["trace[1683140614] 'process raft request' (duration: 336.318493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:03.935256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:03.597724Z","time spent":"337.378793ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:52:03.935129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:03.597306Z","time spent":"337.785921ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:52:04.285809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.652392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:52:04.285947Z","caller":"traceutil/trace.go:171","msg":"trace[1904772060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25787; }","duration":"136.875076ms","start":"2026-05-20T06:52:04.149044Z","end":"2026-05-20T06:52:04.285919Z","steps":["trace[1904772060] 'range keys from in-memory index tree' (duration: 136.53982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:04.287112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.090794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-20T06:52:04.287214Z","caller":"traceutil/trace.go:171","msg":"trace[1922890575] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25787; }","duration":"177.240456ms","start":"2026-05-20T06:52:04.109951Z","end":"2026-05-20T06:52:04.287191Z","steps":["trace[1922890575] 'range keys from in-memory index tree' (duration: 176.930161ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:04.287646Z","caller":"traceutil/trace.go:171","msg":"trace[323149513] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"126.993978ms","start":"2026-05-20T06:52:04.160631Z","end":"2026-05-20T06:52:04.287625Z","steps":["trace[323149513] 'process raft request' (duration: 107.008657ms)","trace[323149513] 'compare' (duration: 18.077266ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:04.864494Z","caller":"traceutil/trace.go:171","msg":"trace[737734315] linearizableReadLoop","detail":"{readStateIndex:26619; appliedIndex:26618; }","duration":"210.331845ms","start":"2026-05-20T06:52:04.65408Z","end":"2026-05-20T06:52:04.864412Z","steps":["trace[737734315] 'read index received' (duration: 135.492621ms)","trace[737734315] 'applied index is now lower than readState.Index' (duration: 74.837234ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:04.864689Z","caller":"traceutil/trace.go:171","msg":"trace[770547248] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"295.321633ms","start":"2026-05-20T06:52:04.569351Z","end":"2026-05-20T06:52:04.864672Z","steps":["trace[770547248] 'process raft request' (duration: 220.360537ms)","trace[770547248] 'compare' (duration: 74.11999ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:04.86487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.753102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-20T06:52:04.864919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.730019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-20T06:52:04.864957Z","caller":"traceutil/trace.go:171","msg":"trace[40445778] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25791; }","duration":"210.911175ms","start":"2026-05-20T06:52:04.654028Z","end":"2026-05-20T06:52:04.864939Z","steps":["trace[40445778] 'agreement among raft nodes before linearized reading' (duration: 210.731432ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:04.864966Z","caller":"traceutil/trace.go:171","msg":"trace[1377729361] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25791; }","duration":"201.817951ms","start":"2026-05-20T06:52:04.66313Z","end":"2026-05-20T06:52:04.864948Z","steps":["trace[1377729361] 'agreement among raft nodes before linearized reading' (duration: 201.632147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:04.865118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.295873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-20T06:52:04.865204Z","caller":"traceutil/trace.go:171","msg":"trace[1546672308] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25791; }","duration":"179.455836ms","start":"2026-05-20T06:52:04.685727Z","end":"2026-05-20T06:52:04.865182Z","steps":["trace[1546672308] 'agreement among raft nodes before linearized reading' (duration: 179.167641ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:05.224812Z","caller":"traceutil/trace.go:171","msg":"trace[1732757614] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"304.554118ms","start":"2026-05-20T06:52:04.920231Z","end":"2026-05-20T06:52:05.224786Z","steps":["trace[1732757614] 'process raft request' (duration: 293.440088ms)","trace[1732757614] 'compare' (duration: 10.778324ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:05.225015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:04.920212Z","time spent":"304.665381ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:52:05.482512Z","caller":"traceutil/trace.go:171","msg":"trace[1919661895] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"218.045284ms","start":"2026-05-20T06:52:05.264444Z","end":"2026-05-20T06:52:05.482489Z","steps":["trace[1919661895] 'process raft request' (duration: 217.222659ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:05.484191Z","caller":"traceutil/trace.go:171","msg":"trace[587609244] linearizableReadLoop","detail":"{readStateIndex:26623; appliedIndex:26620; }","duration":"175.947293ms","start":"2026-05-20T06:52:05.30819Z","end":"2026-05-20T06:52:05.484138Z","steps":["trace[587609244] 'read index received' (duration: 173.433597ms)","trace[587609244] 'applied index is now lower than readState.Index' (duration: 2.511846ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:05.48465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.431321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-20T06:52:05.484742Z","caller":"traceutil/trace.go:171","msg":"trace[1790293438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25795; }","duration":"176.560443ms","start":"2026-05-20T06:52:05.30816Z","end":"2026-05-20T06:52:05.48472Z","steps":["trace[1790293438] 'agreement among raft nodes before linearized reading' (duration: 176.300959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:05.48478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.195024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-05-20T06:52:05.48484Z","caller":"traceutil/trace.go:171","msg":"trace[1593098716] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"218.701806ms","start":"2026-05-20T06:52:05.26611Z","end":"2026-05-20T06:52:05.484811Z","steps":["trace[1593098716] 'process raft request' (duration: 216.706009ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:05.484896Z","caller":"traceutil/trace.go:171","msg":"trace[2097119869] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25795; }","duration":"172.343487ms","start":"2026-05-20T06:52:05.312523Z","end":"2026-05-20T06:52:05.484866Z","steps":["trace[2097119869] 'agreement among raft nodes before linearized reading' (duration: 172.002801ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:05.485055Z","caller":"traceutil/trace.go:171","msg":"trace[780643540] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"218.021854ms","start":"2026-05-20T06:52:05.267016Z","end":"2026-05-20T06:52:05.485038Z","steps":["trace[780643540] 'process raft request' (duration: 216.978835ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:05.638519Z","caller":"traceutil/trace.go:171","msg":"trace[802157472] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"148.470286ms","start":"2026-05-20T06:52:05.490018Z","end":"2026-05-20T06:52:05.638489Z","steps":["trace[802157472] 'process raft request' (duration: 143.714529ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:05.661086Z","caller":"traceutil/trace.go:171","msg":"trace[585089607] transaction","detail":"{read_only:false; response_revision:25797; number_of_response:1; }","duration":"169.68623ms","start":"2026-05-20T06:52:05.491367Z","end":"2026-05-20T06:52:05.661053Z","steps":["trace[585089607] 'process raft request' (duration: 169.446966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:05.961731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.897728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:52:05.961863Z","caller":"traceutil/trace.go:171","msg":"trace[819599534] linearizableReadLoop","detail":"{readStateIndex:26628; appliedIndex:26626; }","duration":"253.677397ms","start":"2026-05-20T06:52:05.708173Z","end":"2026-05-20T06:52:05.96185Z","steps":["trace[819599534] 'read index received' (duration: 112.692657ms)","trace[819599534] 'applied index is now lower than readState.Index' (duration: 140.98407ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:05.962141Z","caller":"traceutil/trace.go:171","msg":"trace[334010434] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"268.284452ms","start":"2026-05-20T06:52:05.693826Z","end":"2026-05-20T06:52:05.96211Z","steps":["trace[334010434] 'process raft request' (duration: 267.987017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:05.962164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.954193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-05-20T06:52:05.962177Z","caller":"traceutil/trace.go:171","msg":"trace[2083235197] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"295.167389ms","start":"2026-05-20T06:52:05.666952Z","end":"2026-05-20T06:52:05.962119Z","steps":["trace[2083235197] 'process raft request' (duration: 153.793502ms)","trace[2083235197] 'compare' (duration: 140.558193ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:05.962276Z","caller":"traceutil/trace.go:171","msg":"trace[1565060079] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25800; }","duration":"254.112995ms","start":"2026-05-20T06:52:05.708137Z","end":"2026-05-20T06:52:05.96225Z","steps":["trace[1565060079] 'agreement among raft nodes before linearized reading' (duration: 253.78882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:06.298968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.645851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:52:06.299105Z","caller":"traceutil/trace.go:171","msg":"trace[1360235612] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"332.588116ms","start":"2026-05-20T06:52:05.966491Z","end":"2026-05-20T06:52:06.299079Z","steps":["trace[1360235612] 'process raft request' (duration: 153.71407ms)","trace[1360235612] 'compare' (duration: 178.297194ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:06.299169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:05.966468Z","time spent":"332.678938ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:52:06.311043Z","caller":"traceutil/trace.go:171","msg":"trace[480317046] linearizableReadLoop","detail":"{readStateIndex:26631; appliedIndex:26629; }","duration":"161.146165ms","start":"2026-05-20T06:52:06.149866Z","end":"2026-05-20T06:52:06.311012Z","steps":["trace[480317046] 'read index received' (duration: 161.007423ms)","trace[480317046] 'applied index is now lower than readState.Index' (duration: 134.192µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:06.311097Z","caller":"traceutil/trace.go:171","msg":"trace[917410129] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"344.181116ms","start":"2026-05-20T06:52:05.966868Z","end":"2026-05-20T06:52:06.311049Z","steps":["trace[917410129] 'process raft request' (duration: 343.938211ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:06.311114Z","caller":"traceutil/trace.go:171","msg":"trace[132917133] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"343.949631ms","start":"2026-05-20T06:52:05.967124Z","end":"2026-05-20T06:52:06.311073Z","steps":["trace[132917133] 'process raft request' (duration: 343.831099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:06.311283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.385439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-20T06:52:06.3113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:05.966855Z","time spent":"344.338588ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:52:06.311307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:05.967116Z","time spent":"344.083923ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:52:06.311334Z","caller":"traceutil/trace.go:171","msg":"trace[7451195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25803; }","duration":"161.484001ms","start":"2026-05-20T06:52:06.149834Z","end":"2026-05-20T06:52:06.311318Z","steps":["trace[7451195] 'agreement among raft nodes before linearized reading' (duration: 161.334179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:06.604034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.950681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:52:06.60423Z","caller":"traceutil/trace.go:171","msg":"trace[237631178] linearizableReadLoop","detail":"{readStateIndex:26633; appliedIndex:26631; }","duration":"293.080152ms","start":"2026-05-20T06:52:06.311133Z","end":"2026-05-20T06:52:06.604214Z","steps":["trace[237631178] 'read index received' (duration: 147.810664ms)","trace[237631178] 'applied index is now lower than readState.Index' (duration: 145.268258ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:06.604319Z","caller":"traceutil/trace.go:171","msg":"trace[951626251] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"393.125091ms","start":"2026-05-20T06:52:06.211178Z","end":"2026-05-20T06:52:06.604304Z","steps":["trace[951626251] 'process raft request' (duration: 247.800683ms)","trace[951626251] 'compare' (duration: 144.794878ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:06.604385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:06.211159Z","time spent":"393.193412ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:52:06.604421Z","caller":"traceutil/trace.go:171","msg":"trace[1320463461] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"310.187401ms","start":"2026-05-20T06:52:06.294192Z","end":"2026-05-20T06:52:06.604379Z","steps":["trace[1320463461] 'process raft request' (duration: 309.951136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:06.604538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.252083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-20T06:52:06.604616Z","caller":"traceutil/trace.go:171","msg":"trace[110527517] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25805; }","duration":"325.383306ms","start":"2026-05-20T06:52:06.279216Z","end":"2026-05-20T06:52:06.604599Z","steps":["trace[110527517] 'agreement among raft nodes before linearized reading' (duration: 325.147201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:06.604625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:06.294137Z","time spent":"310.376834ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:52:06.60465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:06.279199Z","time spent":"325.445256ms","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-20T06:52:06.623911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.574933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-20T06:52:06.623992Z","caller":"traceutil/trace.go:171","msg":"trace[1484217439] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25806; }","duration":"245.704674ms","start":"2026-05-20T06:52:06.378269Z","end":"2026-05-20T06:52:06.623974Z","steps":["trace[1484217439] 'agreement among raft nodes before linearized reading' (duration: 245.47586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:06.907644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.924211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-20T06:52:06.907835Z","caller":"traceutil/trace.go:171","msg":"trace[1465529112] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25806; }","duration":"203.156045ms","start":"2026-05-20T06:52:06.704658Z","end":"2026-05-20T06:52:06.907814Z","steps":["trace[1465529112] 'range keys from in-memory index tree' (duration: 202.733268ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:07.618689Z","caller":"traceutil/trace.go:171","msg":"trace[1307880656] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"127.213341ms","start":"2026-05-20T06:52:07.491438Z","end":"2026-05-20T06:52:07.618652Z","steps":["trace[1307880656] 'process raft request' (duration: 126.956187ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:07.61909Z","caller":"traceutil/trace.go:171","msg":"trace[75903920] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"127.380885ms","start":"2026-05-20T06:52:07.491683Z","end":"2026-05-20T06:52:07.619064Z","steps":["trace[75903920] 'process raft request' (duration: 126.912546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:07.829437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.761922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-20T06:52:07.829572Z","caller":"traceutil/trace.go:171","msg":"trace[974093835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25810; }","duration":"184.953635ms","start":"2026-05-20T06:52:07.644558Z","end":"2026-05-20T06:52:07.829511Z","steps":["trace[974093835] 'range keys from in-memory index tree' (duration: 184.515157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:07.829949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.020407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-05-20T06:52:07.829987Z","caller":"traceutil/trace.go:171","msg":"trace[1952023910] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25810; }","duration":"164.112808ms","start":"2026-05-20T06:52:07.665861Z","end":"2026-05-20T06:52:07.829974Z","steps":["trace[1952023910] 'range keys from in-memory index tree' (duration: 163.833543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:07.83027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.2191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-20T06:52:07.8303Z","caller":"traceutil/trace.go:171","msg":"trace[1648641377] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25810; }","duration":"143.248481ms","start":"2026-05-20T06:52:07.687042Z","end":"2026-05-20T06:52:07.830291Z","steps":["trace[1648641377] 'range keys from in-memory index tree' (duration: 143.108328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:08.191862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.517759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:52:08.192144Z","caller":"traceutil/trace.go:171","msg":"trace[1565574630] linearizableReadLoop","detail":"{readStateIndex:26641; appliedIndex:26639; }","duration":"224.816986ms","start":"2026-05-20T06:52:07.9673Z","end":"2026-05-20T06:52:08.192117Z","steps":["trace[1565574630] 'read index received' (duration: 34.701µs)","trace[1565574630] 'applied index is now lower than readState.Index' (duration: 224.780745ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:08.192172Z","caller":"traceutil/trace.go:171","msg":"trace[1381220697] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"354.602044ms","start":"2026-05-20T06:52:07.837527Z","end":"2026-05-20T06:52:08.192129Z","steps":["trace[1381220697] 'process raft request' (duration: 128.665688ms)","trace[1381220697] 'compare' (duration: 225.315825ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:08.192308Z","caller":"traceutil/trace.go:171","msg":"trace[1687539003] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"354.688235ms","start":"2026-05-20T06:52:07.837572Z","end":"2026-05-20T06:52:08.192261Z","steps":["trace[1687539003] 'process raft request' (duration: 354.4428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:08.192441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.138813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-20T06:52:08.1925Z","caller":"traceutil/trace.go:171","msg":"trace[1149384212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25813; }","duration":"225.191344ms","start":"2026-05-20T06:52:07.967283Z","end":"2026-05-20T06:52:08.192475Z","steps":["trace[1149384212] 'agreement among raft nodes before linearized reading' (duration: 224.909138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:08.192503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:07.837567Z","time spent":"354.825228ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:52:08.19279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:07.837516Z","time spent":"354.780226ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:52:08.253544Z","caller":"traceutil/trace.go:171","msg":"trace[1691162429] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"250.596222ms","start":"2026-05-20T06:52:08.00292Z","end":"2026-05-20T06:52:08.253516Z","steps":["trace[1691162429] 'process raft request' (duration: 250.386928ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:08.489986Z","caller":"traceutil/trace.go:171","msg":"trace[2081640062] linearizableReadLoop","detail":"{readStateIndex:26643; appliedIndex:26642; }","duration":"225.716943ms","start":"2026-05-20T06:52:08.264203Z","end":"2026-05-20T06:52:08.48992Z","steps":["trace[2081640062] 'read index received' (duration: 207.547775ms)","trace[2081640062] 'applied index is now lower than readState.Index' (duration: 18.167538ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:08.490134Z","caller":"traceutil/trace.go:171","msg":"trace[1621167234] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"293.00813ms","start":"2026-05-20T06:52:08.197093Z","end":"2026-05-20T06:52:08.490102Z","steps":["trace[1621167234] 'process raft request' (duration: 274.920022ms)","trace[1621167234] 'compare' (duration: 17.694061ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:08.490368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.11502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18255"} {"level":"info","ts":"2026-05-20T06:52:08.490419Z","caller":"traceutil/trace.go:171","msg":"trace[1804345627] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25815; }","duration":"226.213882ms","start":"2026-05-20T06:52:08.264193Z","end":"2026-05-20T06:52:08.490406Z","steps":["trace[1804345627] 'agreement among raft nodes before linearized reading' (duration: 225.925407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:08.490427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.381559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.30\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-05-20T06:52:08.490459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.964921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:52:08.490519Z","caller":"traceutil/trace.go:171","msg":"trace[757793083] range","detail":"{range_begin:/registry/masterleases/199.19.213.30; range_end:; response_count:1; response_revision:25815; }","duration":"208.523412ms","start":"2026-05-20T06:52:08.281974Z","end":"2026-05-20T06:52:08.490497Z","steps":["trace[757793083] 'agreement among raft nodes before linearized reading' (duration: 208.282987ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:08.490545Z","caller":"traceutil/trace.go:171","msg":"trace[619357316] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:25815; }","duration":"208.071973ms","start":"2026-05-20T06:52:08.282453Z","end":"2026-05-20T06:52:08.490525Z","steps":["trace[619357316] 'agreement among raft nodes before linearized reading' (duration: 207.89361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:08.490584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.438769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-20T06:52:08.490594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.817523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-20T06:52:08.490638Z","caller":"traceutil/trace.go:171","msg":"trace[1120033534] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:25815; }","duration":"208.514341ms","start":"2026-05-20T06:52:08.282107Z","end":"2026-05-20T06:52:08.490622Z","steps":["trace[1120033534] 'agreement among raft nodes before linearized reading' (duration: 208.406779ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:08.490653Z","caller":"traceutil/trace.go:171","msg":"trace[285298312] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25815; }","duration":"173.897015ms","start":"2026-05-20T06:52:08.316744Z","end":"2026-05-20T06:52:08.490641Z","steps":["trace[285298312] 'agreement among raft nodes before linearized reading' (duration: 173.772033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:08.490663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.78186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-20T06:52:08.490702Z","caller":"traceutil/trace.go:171","msg":"trace[784092481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25815; }","duration":"174.847062ms","start":"2026-05-20T06:52:08.315844Z","end":"2026-05-20T06:52:08.490691Z","steps":["trace[784092481] 'agreement among raft nodes before linearized reading' (duration: 174.7322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:08.490451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.34924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-20T06:52:08.491043Z","caller":"traceutil/trace.go:171","msg":"trace[851691885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25815; }","duration":"186.959032ms","start":"2026-05-20T06:52:08.304071Z","end":"2026-05-20T06:52:08.49103Z","steps":["trace[851691885] 'agreement among raft nodes before linearized reading' (duration: 186.205609ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:08.729883Z","caller":"traceutil/trace.go:171","msg":"trace[624631385] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"162.53397ms","start":"2026-05-20T06:52:08.567314Z","end":"2026-05-20T06:52:08.729848Z","steps":["trace[624631385] 'process raft request' (duration: 81.29203ms)","trace[624631385] 'compare' (duration: 81.090447ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:08.783369Z","caller":"traceutil/trace.go:171","msg":"trace[1782357140] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"173.181952ms","start":"2026-05-20T06:52:08.610151Z","end":"2026-05-20T06:52:08.783333Z","steps":["trace[1782357140] 'process raft request' (duration: 172.955078ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:08.783638Z","caller":"traceutil/trace.go:171","msg":"trace[1611327272] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"152.198313ms","start":"2026-05-20T06:52:08.631386Z","end":"2026-05-20T06:52:08.783584Z","steps":["trace[1611327272] 'process raft request' (duration: 151.901417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:09.08641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.407762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:52:09.086481Z","caller":"traceutil/trace.go:171","msg":"trace[682385620] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25821; }","duration":"196.487273ms","start":"2026-05-20T06:52:08.889972Z","end":"2026-05-20T06:52:09.086459Z","steps":["trace[682385620] 'count revisions from in-memory index tree' (duration: 196.308521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:09.087181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.096873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:52:09.087306Z","caller":"traceutil/trace.go:171","msg":"trace[1037963571] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"173.455547ms","start":"2026-05-20T06:52:08.913828Z","end":"2026-05-20T06:52:09.087284Z","steps":["trace[1037963571] 'process raft request' (duration: 43.15321ms)","trace[1037963571] 'compare' (duration: 129.863879ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:09.367833Z","caller":"traceutil/trace.go:171","msg":"trace[1235132513] linearizableReadLoop","detail":"{readStateIndex:26652; appliedIndex:26651; }","duration":"169.871563ms","start":"2026-05-20T06:52:09.197935Z","end":"2026-05-20T06:52:09.367806Z","steps":["trace[1235132513] 'read index received' (duration: 101.415635ms)","trace[1235132513] 'applied index is now lower than readState.Index' (duration: 68.454688ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:09.367876Z","caller":"traceutil/trace.go:171","msg":"trace[1794169625] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"188.729194ms","start":"2026-05-20T06:52:09.17911Z","end":"2026-05-20T06:52:09.367839Z","steps":["trace[1794169625] 'process raft request' (duration: 120.203225ms)","trace[1794169625] 'compare' (duration: 68.276804ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:09.368004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.051906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-20T06:52:09.36804Z","caller":"traceutil/trace.go:171","msg":"trace[152961912] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:25823; }","duration":"170.146838ms","start":"2026-05-20T06:52:09.197884Z","end":"2026-05-20T06:52:09.368031Z","steps":["trace[152961912] 'agreement among raft nodes before linearized reading' (duration: 170.032246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:09.368224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.508701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-20T06:52:09.368266Z","caller":"traceutil/trace.go:171","msg":"trace[489148809] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25823; }","duration":"161.597472ms","start":"2026-05-20T06:52:09.206659Z","end":"2026-05-20T06:52:09.368256Z","steps":["trace[489148809] 'agreement among raft nodes before linearized reading' (duration: 161.359508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:09.952981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.739834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:52:09.953172Z","caller":"traceutil/trace.go:171","msg":"trace[1181597015] linearizableReadLoop","detail":"{readStateIndex:26655; appliedIndex:26652; }","duration":"306.205599ms","start":"2026-05-20T06:52:09.646946Z","end":"2026-05-20T06:52:09.953152Z","steps":["trace[1181597015] 'read index received' (duration: 8.069936ms)","trace[1181597015] 'applied index is now lower than readState.Index' (duration: 298.134513ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:09.953277Z","caller":"traceutil/trace.go:171","msg":"trace[669118554] transaction","detail":"{read_only:false; response_revision:25826; number_of_response:1; }","duration":"326.850813ms","start":"2026-05-20T06:52:09.626412Z","end":"2026-05-20T06:52:09.953263Z","steps":["trace[669118554] 'process raft request' (duration: 326.67421ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:09.953282Z","caller":"traceutil/trace.go:171","msg":"trace[1239413948] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"327.895661ms","start":"2026-05-20T06:52:09.62536Z","end":"2026-05-20T06:52:09.953256Z","steps":["trace[1239413948] 'process raft request' (duration: 159.758449ms)","trace[1239413948] 'compare' (duration: 167.578302ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:09.953579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:09.626381Z","time spent":"326.923853ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:52:09.953617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:09.625331Z","time spent":"328.214687ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:52:09.95371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.328031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:52:09.953777Z","caller":"traceutil/trace.go:171","msg":"trace[716701089] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25826; }","duration":"290.452444ms","start":"2026-05-20T06:52:09.663308Z","end":"2026-05-20T06:52:09.953761Z","steps":["trace[716701089] 'agreement among raft nodes before linearized reading' (duration: 290.319621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:09.953863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.918961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-20T06:52:09.953928Z","caller":"traceutil/trace.go:171","msg":"trace[1534863021] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25826; }","duration":"307.010552ms","start":"2026-05-20T06:52:09.646903Z","end":"2026-05-20T06:52:09.953914Z","steps":["trace[1534863021] 'agreement among raft nodes before linearized reading' (duration: 306.915201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:09.953966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:09.646882Z","time spent":"307.074904ms","remote":"127.0.0.1:56568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-20T06:52:10.421164Z","caller":"traceutil/trace.go:171","msg":"trace[1561509785] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"199.329155ms","start":"2026-05-20T06:52:10.221788Z","end":"2026-05-20T06:52:10.421117Z","steps":["trace[1561509785] 'process raft request' (duration: 127.19597ms)","trace[1561509785] 'compare' (duration: 71.667037ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:10.741587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.094417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:52:10.74169Z","caller":"traceutil/trace.go:171","msg":"trace[791556639] linearizableReadLoop","detail":"{readStateIndex:26659; appliedIndex:26658; }","duration":"213.987072ms","start":"2026-05-20T06:52:10.527689Z","end":"2026-05-20T06:52:10.741676Z","steps":["trace[791556639] 'read index received' (duration: 33.731101ms)","trace[791556639] 'applied index is now lower than readState.Index' (duration: 180.255001ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:10.741841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.308178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-20T06:52:10.741885Z","caller":"traceutil/trace.go:171","msg":"trace[1833281699] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25830; }","duration":"161.350599ms","start":"2026-05-20T06:52:10.580526Z","end":"2026-05-20T06:52:10.741876Z","steps":["trace[1833281699] 'agreement among raft nodes before linearized reading' (duration: 161.230907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:10.741901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.215155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-20T06:52:10.74196Z","caller":"traceutil/trace.go:171","msg":"trace[424980453] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:25830; }","duration":"214.348297ms","start":"2026-05-20T06:52:10.527595Z","end":"2026-05-20T06:52:10.741944Z","steps":["trace[424980453] 'agreement among raft nodes before linearized reading' (duration: 214.151574ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:10.7419Z","caller":"traceutil/trace.go:171","msg":"trace[755765713] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"241.212553ms","start":"2026-05-20T06:52:10.50064Z","end":"2026-05-20T06:52:10.741852Z","steps":["trace[755765713] 'process raft request' (duration: 60.791009ms)","trace[755765713] 'compare' (duration: 179.783601ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:10.742272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.711379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-20T06:52:10.742295Z","caller":"traceutil/trace.go:171","msg":"trace[1279305511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25830; }","duration":"159.754639ms","start":"2026-05-20T06:52:10.582535Z","end":"2026-05-20T06:52:10.74229Z","steps":["trace[1279305511] 'agreement among raft nodes before linearized reading' (duration: 159.699169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:10.742358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.930773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-20T06:52:10.742399Z","caller":"traceutil/trace.go:171","msg":"trace[140615746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25830; }","duration":"160.013524ms","start":"2026-05-20T06:52:10.582375Z","end":"2026-05-20T06:52:10.742389Z","steps":["trace[140615746] 'agreement among raft nodes before linearized reading' (duration: 159.910952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:11.064895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.364845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:52:11.065306Z","caller":"traceutil/trace.go:171","msg":"trace[1261127585] linearizableReadLoop","detail":"{readStateIndex:26664; appliedIndex:26659; }","duration":"268.63601ms","start":"2026-05-20T06:52:10.796654Z","end":"2026-05-20T06:52:11.06529Z","steps":["trace[1261127585] 'read index received' (duration: 114.790597ms)","trace[1261127585] 'applied index is now lower than readState.Index' (duration: 153.844093ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:11.065419Z","caller":"traceutil/trace.go:171","msg":"trace[315291170] transaction","detail":"{read_only:false; response_revision:25831; number_of_response:1; }","duration":"316.063877ms","start":"2026-05-20T06:52:10.749328Z","end":"2026-05-20T06:52:11.065392Z","steps":["trace[315291170] 'process raft request' (duration: 162.098422ms)","trace[315291170] 'compare' (duration: 152.963508ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:11.065473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:10.749306Z","time spent":"316.137888ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:52:11.065676Z","caller":"traceutil/trace.go:171","msg":"trace[1579004520] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"273.353404ms","start":"2026-05-20T06:52:10.792293Z","end":"2026-05-20T06:52:11.065646Z","steps":["trace[1579004520] 'process raft request' (duration: 272.962077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:11.065731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.074747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:52:11.065757Z","caller":"traceutil/trace.go:171","msg":"trace[124391264] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25835; }","duration":"269.147199ms","start":"2026-05-20T06:52:10.7966Z","end":"2026-05-20T06:52:11.065748Z","steps":["trace[124391264] 'agreement among raft nodes before linearized reading' (duration: 269.078778ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:11.065944Z","caller":"traceutil/trace.go:171","msg":"trace[1036859597] transaction","detail":"{read_only:false; response_revision:25834; number_of_response:1; }","duration":"275.257739ms","start":"2026-05-20T06:52:10.790651Z","end":"2026-05-20T06:52:11.065908Z","steps":["trace[1036859597] 'process raft request' (duration: 274.578027ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:11.06604Z","caller":"traceutil/trace.go:171","msg":"trace[969380963] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"315.472626ms","start":"2026-05-20T06:52:10.750516Z","end":"2026-05-20T06:52:11.065989Z","steps":["trace[969380963] 'process raft request' (duration: 314.681262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:11.066303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:10.750508Z","time spent":"315.643419ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T06:52:11.066221Z","caller":"traceutil/trace.go:171","msg":"trace[1485953460] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"316.492565ms","start":"2026-05-20T06:52:10.749684Z","end":"2026-05-20T06:52:11.066176Z","steps":["trace[1485953460] 'process raft request' (duration: 315.322575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:11.066647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T06:52:10.749672Z","time spent":"316.839862ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T06:52:11.392194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.344955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T06:52:11.392307Z","caller":"traceutil/trace.go:171","msg":"trace[1519215812] linearizableReadLoop","detail":"{readStateIndex:26665; appliedIndex:26664; }","duration":"282.013401ms","start":"2026-05-20T06:52:11.110274Z","end":"2026-05-20T06:52:11.392287Z","steps":["trace[1519215812] 'read index received' (duration: 118.477943ms)","trace[1519215812] 'applied index is now lower than readState.Index' (duration: 163.533628ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:11.39243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.311615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:52:11.392457Z","caller":"traceutil/trace.go:171","msg":"trace[1485362215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25836; }","duration":"241.376266ms","start":"2026-05-20T06:52:11.151072Z","end":"2026-05-20T06:52:11.392448Z","steps":["trace[1485362215] 'agreement among raft nodes before linearized reading' (duration: 241.305775ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:11.392435Z","caller":"traceutil/trace.go:171","msg":"trace[955945156] transaction","detail":"{read_only:false; response_revision:25836; number_of_response:1; }","duration":"299.032779ms","start":"2026-05-20T06:52:11.093337Z","end":"2026-05-20T06:52:11.39237Z","steps":["trace[955945156] 'process raft request' (duration: 135.408949ms)","trace[955945156] 'compare' (duration: 163.227903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:52:11.392781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.187974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:52:11.3929Z","caller":"traceutil/trace.go:171","msg":"trace[1026884954] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25836; }","duration":"282.665813ms","start":"2026-05-20T06:52:11.110207Z","end":"2026-05-20T06:52:11.392873Z","steps":["trace[1026884954] 'agreement among raft nodes before linearized reading' (duration: 282.164184ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:12.284753Z","caller":"traceutil/trace.go:171","msg":"trace[604669619] transaction","detail":"{read_only:false; response_revision:25842; number_of_response:1; }","duration":"101.701029ms","start":"2026-05-20T06:52:12.183022Z","end":"2026-05-20T06:52:12.284723Z","steps":["trace[604669619] 'process raft request' (duration: 101.495785ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:14.438186Z","caller":"traceutil/trace.go:171","msg":"trace[1339949287] transaction","detail":"{read_only:false; response_revision:25857; number_of_response:1; }","duration":"129.506153ms","start":"2026-05-20T06:52:14.308663Z","end":"2026-05-20T06:52:14.43817Z","steps":["trace[1339949287] 'process raft request' (duration: 129.064015ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:14.547478Z","caller":"traceutil/trace.go:171","msg":"trace[1708358535] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"104.598062ms","start":"2026-05-20T06:52:14.442861Z","end":"2026-05-20T06:52:14.547459Z","steps":["trace[1708358535] 'process raft request' (duration: 103.664025ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:26.837154Z","caller":"traceutil/trace.go:171","msg":"trace[966094895] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"165.101726ms","start":"2026-05-20T06:52:26.672027Z","end":"2026-05-20T06:52:26.837128Z","steps":["trace[966094895] 'process raft request' (duration: 164.928863ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:26.890715Z","caller":"traceutil/trace.go:171","msg":"trace[2119125369] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"207.709497ms","start":"2026-05-20T06:52:26.682974Z","end":"2026-05-20T06:52:26.890684Z","steps":["trace[2119125369] 'process raft request' (duration: 195.264732ms)","trace[2119125369] 'compare' (duration: 12.17583ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:27.330746Z","caller":"traceutil/trace.go:171","msg":"trace[1416944335] transaction","detail":"{read_only:false; response_revision:25962; number_of_response:1; }","duration":"121.359755ms","start":"2026-05-20T06:52:27.209343Z","end":"2026-05-20T06:52:27.330703Z","steps":["trace[1416944335] 'process raft request' (duration: 121.130961ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:27.406175Z","caller":"traceutil/trace.go:171","msg":"trace[988699915] linearizableReadLoop","detail":"{readStateIndex:26796; appliedIndex:26793; }","duration":"103.520782ms","start":"2026-05-20T06:52:27.302614Z","end":"2026-05-20T06:52:27.406135Z","steps":["trace[988699915] 'read index received' (duration: 27.949505ms)","trace[988699915] 'applied index is now lower than readState.Index' (duration: 75.569787ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:52:27.406249Z","caller":"traceutil/trace.go:171","msg":"trace[1130417929] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"196.387302ms","start":"2026-05-20T06:52:27.20981Z","end":"2026-05-20T06:52:27.406197Z","steps":["trace[1130417929] 'process raft request' (duration: 196.078227ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:27.406292Z","caller":"traceutil/trace.go:171","msg":"trace[1608490273] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"195.254141ms","start":"2026-05-20T06:52:27.210973Z","end":"2026-05-20T06:52:27.406227Z","steps":["trace[1608490273] 'process raft request' (duration: 195.096748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:27.406629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.997651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-20T06:52:27.406755Z","caller":"traceutil/trace.go:171","msg":"trace[1328529744] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25964; }","duration":"104.147934ms","start":"2026-05-20T06:52:27.30258Z","end":"2026-05-20T06:52:27.406728Z","steps":["trace[1328529744] 'agreement among raft nodes before linearized reading' (duration: 103.707526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:52:27.535355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.3606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-20T06:52:27.535469Z","caller":"traceutil/trace.go:171","msg":"trace[592567053] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25964; }","duration":"113.512863ms","start":"2026-05-20T06:52:27.421922Z","end":"2026-05-20T06:52:27.535435Z","steps":["trace[592567053] 'count revisions from in-memory index tree' (duration: 113.299169ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:52:42.10485Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006953.wal"} {"level":"info","ts":"2026-05-20T06:52:45.125836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23177} {"level":"info","ts":"2026-05-20T06:52:45.262917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23177,"took":"131.214944ms","hash":1747693942,"current-db-size-bytes":56508416,"current-db-size":"56 MB","current-db-size-in-use-bytes":40329216,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-05-20T06:52:45.26299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1747693942,"revision":23177,"compact-revision":20350} {"level":"info","ts":"2026-05-20T06:55:54.203133Z","caller":"traceutil/trace.go:171","msg":"trace[1631271867] linearizableReadLoop","detail":"{readStateIndex:29051; appliedIndex:29049; }","duration":"142.626006ms","start":"2026-05-20T06:55:54.060472Z","end":"2026-05-20T06:55:54.203098Z","steps":["trace[1631271867] 'read index received' (duration: 35.59832ms)","trace[1631271867] 'applied index is now lower than readState.Index' (duration: 107.026526ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T06:55:54.203321Z","caller":"traceutil/trace.go:171","msg":"trace[2124445262] transaction","detail":"{read_only:false; response_revision:28165; number_of_response:1; }","duration":"145.43778ms","start":"2026-05-20T06:55:54.05783Z","end":"2026-05-20T06:55:54.203268Z","steps":["trace[2124445262] 'process raft request' (duration: 101.792966ms)","trace[2124445262] 'compare' (duration: 43.343809ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T06:55:54.203476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.979725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-20T06:55:54.203519Z","caller":"traceutil/trace.go:171","msg":"trace[1844426047] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28165; }","duration":"143.082837ms","start":"2026-05-20T06:55:54.060421Z","end":"2026-05-20T06:55:54.203504Z","steps":["trace[1844426047] 'agreement among raft nodes before linearized reading' (duration: 142.858231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T06:55:54.204032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.885177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-20T06:55:54.204117Z","caller":"traceutil/trace.go:171","msg":"trace[140200340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28165; }","duration":"116.024539ms","start":"2026-05-20T06:55:54.088074Z","end":"2026-05-20T06:55:54.204099Z","steps":["trace[140200340] 'agreement among raft nodes before linearized reading' (duration: 115.452408ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T06:57:45.130617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26235} {"level":"info","ts":"2026-05-20T06:57:45.274185Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26235,"took":"139.367717ms","hash":1121914099,"current-db-size-bytes":56508416,"current-db-size":"56 MB","current-db-size-in-use-bytes":35864576,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-20T06:57:45.27425Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1121914099,"revision":26235,"compact-revision":23177} {"level":"info","ts":"2026-05-20T06:57:55.922181Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2b5783e11defe7e","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-20T06:57:55.926323Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-20T06:57:55.926435Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-20T07:02:45.147036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29011} {"level":"info","ts":"2026-05-20T07:02:45.281285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29011,"took":"130.184393ms","hash":80000049,"current-db-size-bytes":56508416,"current-db-size":"56 MB","current-db-size-in-use-bytes":32231424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-20T07:02:45.281347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":80000049,"revision":29011,"compact-revision":26235} {"level":"info","ts":"2026-05-20T07:07:45.155565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31343} {"level":"info","ts":"2026-05-20T07:07:45.300914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31343,"took":"140.681706ms","hash":3693222341,"current-db-size-bytes":56508416,"current-db-size":"56 MB","current-db-size-in-use-bytes":32219136,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-20T07:07:45.300991Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3693222341,"revision":31343,"compact-revision":29011} {"level":"info","ts":"2026-05-20T07:12:45.162147Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33682} {"level":"info","ts":"2026-05-20T07:12:45.321434Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33682,"took":"153.840314ms","hash":4244491086,"current-db-size-bytes":57974784,"current-db-size":"58 MB","current-db-size-in-use-bytes":32698368,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-20T07:12:45.321496Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4244491086,"revision":33682,"compact-revision":31343} {"level":"info","ts":"2026-05-20T07:17:45.169206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36020} {"level":"info","ts":"2026-05-20T07:17:45.336714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36020,"took":"161.625568ms","hash":2449355105,"current-db-size-bytes":59138048,"current-db-size":"59 MB","current-db-size-in-use-bytes":31805440,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-20T07:17:45.336845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2449355105,"revision":36020,"compact-revision":33682} {"level":"info","ts":"2026-05-20T07:18:59.428949Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2b5783e11defe7e","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-20T07:18:59.435108Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-20T07:18:59.435221Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-20T07:22:45.178056Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38223} {"level":"info","ts":"2026-05-20T07:22:45.296449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38223,"took":"114.40543ms","hash":1129256619,"current-db-size-bytes":59138048,"current-db-size":"59 MB","current-db-size-in-use-bytes":33218560,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-20T07:22:45.29651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1129256619,"revision":38223,"compact-revision":36020}