{"level":"warn","ts":"2026-05-19T11:53:13.556093Z","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-19T11:53:13.556175Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.157:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.157:2380","--initial-cluster=instance=https://199.19.213.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.157:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-19T11:53:13.556234Z","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-19T11:53:13.556247Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.157:2380"]} {"level":"info","ts":"2026-05-19T11:53:13.556263Z","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-19T11:53:13.556822Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"]} {"level":"info","ts":"2026-05-19T11:53:13.55693Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.157:2380"],"listen-peer-urls":["https://199.19.213.157:2380"],"advertise-client-urls":["https://199.19.213.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.157:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-19T11:53:13.562033Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.902647ms"} {"level":"info","ts":"2026-05-19T11:53:13.568531Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"10058a425931b8de","cluster-id":"2ff97ff1d0272754"} {"level":"info","ts":"2026-05-19T11:53:13.568623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T11:53:13.568664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became follower at term 0"} {"level":"info","ts":"2026-05-19T11:53:13.568679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 10058a425931b8de [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T11:53:13.568688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became follower at term 1"} {"level":"info","ts":"2026-05-19T11:53:13.56875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=(1154480897059305694)"} {"level":"warn","ts":"2026-05-19T11:53:13.574603Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T11:53:13.576367Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T11:53:13.577907Z","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-19T11:53:13.582077Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"10058a425931b8de","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T11:53:13.582408Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"10058a425931b8de","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T11:53:13.582725Z","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-19T11:53:13.583111Z","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-19T11:53:13.583214Z","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-19T11:53:13.584986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=(1154480897059305694)"} {"level":"info","ts":"2026-05-19T11:53:13.585097Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ff97ff1d0272754","local-member-id":"10058a425931b8de","added-peer-id":"10058a425931b8de","added-peer-peer-urls":["https://199.19.213.157:2380"]} {"level":"info","ts":"2026-05-19T11:53:13.585716Z","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-19T11:53:13.586281Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.157:2380"} {"level":"info","ts":"2026-05-19T11:53:13.586305Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.157:2380"} {"level":"info","ts":"2026-05-19T11:53:13.586677Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"10058a425931b8de","initial-advertise-peer-urls":["https://199.19.213.157:2380"],"listen-peer-urls":["https://199.19.213.157:2380"],"advertise-client-urls":["https://199.19.213.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T11:53:13.586715Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T11:53:13.669533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T11:53:13.669593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T11:53:13.669615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de received MsgPreVoteResp from 10058a425931b8de at term 1"} {"level":"info","ts":"2026-05-19T11:53:13.669857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became candidate at term 2"} {"level":"info","ts":"2026-05-19T11:53:13.669881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de received MsgVoteResp from 10058a425931b8de at term 2"} {"level":"info","ts":"2026-05-19T11:53:13.670073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became leader at term 2"} {"level":"info","ts":"2026-05-19T11:53:13.6701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 10058a425931b8de elected leader 10058a425931b8de at term 2"} {"level":"info","ts":"2026-05-19T11:53:13.671472Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"10058a425931b8de","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.157:2379]}","request-path":"/0/members/10058a425931b8de/attributes","cluster-id":"2ff97ff1d0272754","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T11:53:13.671492Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T11:53:13.67159Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T11:53:13.671516Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T11:53:13.672475Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ff97ff1d0272754","local-member-id":"10058a425931b8de","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T11:53:13.672638Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T11:53:13.672707Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T11:53:13.673709Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T11:53:13.673803Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T11:53:13.675704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.157:2379"} {"level":"info","ts":"2026-05-19T11:53:13.676288Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T11:53:47.5945Z","caller":"traceutil/trace.go:171","msg":"trace[1589045443] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"247.903054ms","start":"2026-05-19T11:53:47.346571Z","end":"2026-05-19T11:53:47.594474Z","steps":["trace[1589045443] 'read index received' (duration: 246.754676ms)","trace[1589045443] 'applied index is now lower than readState.Index' (duration: 1.147058ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T11:53:47.594632Z","caller":"traceutil/trace.go:171","msg":"trace[397892805] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"295.174964ms","start":"2026-05-19T11:53:47.299419Z","end":"2026-05-19T11:53:47.594594Z","steps":["trace[397892805] 'process raft request' (duration: 294.177431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:53:47.594823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.229514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gg8nl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-19T11:53:47.594926Z","caller":"traceutil/trace.go:171","msg":"trace[1069801934] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gg8nl; range_end:; response_count:1; response_revision:612; }","duration":"248.375608ms","start":"2026-05-19T11:53:47.346534Z","end":"2026-05-19T11:53:47.594909Z","steps":["trace[1069801934] 'agreement among raft nodes before linearized reading' (duration: 248.140031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:53:47.594931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.656261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T11:53:47.595023Z","caller":"traceutil/trace.go:171","msg":"trace[1695145315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"179.819866ms","start":"2026-05-19T11:53:47.415183Z","end":"2026-05-19T11:53:47.595002Z","steps":["trace[1695145315] 'agreement among raft nodes before linearized reading' (duration: 179.648221ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T11:53:47.772913Z","caller":"traceutil/trace.go:171","msg":"trace[295984784] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"153.041253ms","start":"2026-05-19T11:53:47.619836Z","end":"2026-05-19T11:53:47.772877Z","steps":["trace[295984784] 'process raft request' (duration: 152.816386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:53:48.383971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.137782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T11:53:48.385005Z","caller":"traceutil/trace.go:171","msg":"trace[759589109] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"734.668378ms","start":"2026-05-19T11:53:47.6503Z","end":"2026-05-19T11:53:48.384968Z","steps":["trace[759589109] 'process raft request' (duration: 352.883802ms)","trace[759589109] 'compare' (duration: 379.77309ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T11:53:48.385177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T11:53:47.650273Z","time spent":"734.820434ms","remote":"127.0.0.1:51752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T11:53:48.385501Z","caller":"traceutil/trace.go:171","msg":"trace[333145554] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"304.782528ms","start":"2026-05-19T11:53:48.080702Z","end":"2026-05-19T11:53:48.385485Z","steps":["trace[333145554] 'read index received' (duration: 47.491µs)","trace[333145554] 'applied index is now lower than readState.Index' (duration: 304.733477ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T11:53:48.385805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.098949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T11:53:48.385846Z","caller":"traceutil/trace.go:171","msg":"trace[1858529204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"305.187491ms","start":"2026-05-19T11:53:48.080647Z","end":"2026-05-19T11:53:48.385834Z","steps":["trace[1858529204] 'agreement among raft nodes before linearized reading' (duration: 304.959844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:53:48.385878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T11:53:48.080625Z","time spent":"305.245593ms","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T11:53:48.386398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.653066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T11:53:48.386436Z","caller":"traceutil/trace.go:171","msg":"trace[753885352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"305.736479ms","start":"2026-05-19T11:53:48.080689Z","end":"2026-05-19T11:53:48.386425Z","steps":["trace[753885352] 'agreement among raft nodes before linearized reading' (duration: 305.571034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:53:48.386488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T11:53:48.080664Z","time spent":"305.816272ms","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T11:54:04.134514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.894142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T11:54:04.134618Z","caller":"traceutil/trace.go:171","msg":"trace[1152748957] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"361.991399ms","start":"2026-05-19T11:54:03.772614Z","end":"2026-05-19T11:54:04.134606Z","steps":["trace[1152748957] 'read index received' (duration: 111.646744ms)","trace[1152748957] 'applied index is now lower than readState.Index' (duration: 250.343805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T11:54:04.134732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.12981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T11:54:04.134705Z","caller":"traceutil/trace.go:171","msg":"trace[1751115220] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"446.048376ms","start":"2026-05-19T11:54:03.6886Z","end":"2026-05-19T11:54:04.134648Z","steps":["trace[1751115220] 'process raft request' (duration: 195.812483ms)","trace[1751115220] 'compare' (duration: 249.197996ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T11:54:04.134759Z","caller":"traceutil/trace.go:171","msg":"trace[1204398547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"362.218211ms","start":"2026-05-19T11:54:03.772532Z","end":"2026-05-19T11:54:04.13475Z","steps":["trace[1204398547] 'agreement among raft nodes before linearized reading' (duration: 362.10757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:54:04.134789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T11:54:03.772508Z","time spent":"362.27588ms","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T11:54:04.134887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T11:54:03.68858Z","time spent":"446.217648ms","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T11:54:30.924456Z","caller":"traceutil/trace.go:171","msg":"trace[1557018189] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"188.544764ms","start":"2026-05-19T11:54:30.735886Z","end":"2026-05-19T11:54:30.924431Z","steps":["trace[1557018189] 'read index received' (duration: 188.28483ms)","trace[1557018189] 'applied index is now lower than readState.Index' (duration: 258.034µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T11:54:30.924816Z","caller":"traceutil/trace.go:171","msg":"trace[725042874] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"208.094489ms","start":"2026-05-19T11:54:30.716705Z","end":"2026-05-19T11:54:30.924799Z","steps":["trace[725042874] 'process raft request' (duration: 207.5418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:54:30.925076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.149743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T11:54:30.925191Z","caller":"traceutil/trace.go:171","msg":"trace[1021129199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"189.306965ms","start":"2026-05-19T11:54:30.735859Z","end":"2026-05-19T11:54:30.925166Z","steps":["trace[1021129199] 'agreement among raft nodes before linearized reading' (duration: 188.989941ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T11:55:23.87476Z","caller":"traceutil/trace.go:171","msg":"trace[854472928] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"152.013189ms","start":"2026-05-19T11:55:23.722725Z","end":"2026-05-19T11:55:23.874738Z","steps":["trace[854472928] 'read index received' (duration: 151.827825ms)","trace[854472928] 'applied index is now lower than readState.Index' (duration: 184.644µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T11:55:23.87498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.234895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-19T11:55:23.875039Z","caller":"traceutil/trace.go:171","msg":"trace[1465161486] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1868; }","duration":"152.359457ms","start":"2026-05-19T11:55:23.722666Z","end":"2026-05-19T11:55:23.875025Z","steps":["trace[1465161486] 'agreement among raft nodes before linearized reading' (duration: 152.175063ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T11:55:23.874809Z","caller":"traceutil/trace.go:171","msg":"trace[390213280] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"154.269101ms","start":"2026-05-19T11:55:23.720519Z","end":"2026-05-19T11:55:23.874788Z","steps":["trace[390213280] 'process raft request' (duration: 154.089028ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T11:55:30.73089Z","caller":"traceutil/trace.go:171","msg":"trace[2019021302] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1977; }","duration":"122.231177ms","start":"2026-05-19T11:55:30.608637Z","end":"2026-05-19T11:55:30.730868Z","steps":["trace[2019021302] 'read index received' (duration: 45.022174ms)","trace[2019021302] 'applied index is now lower than readState.Index' (duration: 77.208073ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T11:55:30.731151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.464532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T11:55:30.731209Z","caller":"traceutil/trace.go:171","msg":"trace[2047191076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1907; }","duration":"122.596106ms","start":"2026-05-19T11:55:30.608601Z","end":"2026-05-19T11:55:30.731197Z","steps":["trace[2047191076] 'agreement among raft nodes before linearized reading' (duration: 122.35561ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T11:55:30.731087Z","caller":"traceutil/trace.go:171","msg":"trace[1712495528] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"125.640638ms","start":"2026-05-19T11:55:30.605417Z","end":"2026-05-19T11:55:30.731057Z","steps":["trace[1712495528] 'process raft request' (duration: 48.259371ms)","trace[1712495528] 'compare' (duration: 77.09315ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T11:55:44.203814Z","caller":"traceutil/trace.go:171","msg":"trace[1629773635] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"190.057247ms","start":"2026-05-19T11:55:44.013733Z","end":"2026-05-19T11:55:44.20379Z","steps":["trace[1629773635] 'process raft request' (duration: 189.666217ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T11:55:50.466839Z","caller":"traceutil/trace.go:171","msg":"trace[269765852] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"101.135557ms","start":"2026-05-19T11:55:50.365665Z","end":"2026-05-19T11:55:50.4668Z","steps":["trace[269765852] 'process raft request' (duration: 100.86038ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T11:55:58.988725Z","caller":"traceutil/trace.go:171","msg":"trace[1424434476] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"130.093625ms","start":"2026-05-19T11:55:58.858597Z","end":"2026-05-19T11:55:58.988691Z","steps":["trace[1424434476] 'process raft request' (duration: 129.91157ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T11:55:58.994609Z","caller":"traceutil/trace.go:171","msg":"trace[760667246] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"135.636438ms","start":"2026-05-19T11:55:58.858947Z","end":"2026-05-19T11:55:58.994583Z","steps":["trace[760667246] 'process raft request' (duration: 135.465114ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T11:56:00.173021Z","caller":"traceutil/trace.go:171","msg":"trace[595492651] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"118.655172ms","start":"2026-05-19T11:56:00.05434Z","end":"2026-05-19T11:56:00.172995Z","steps":["trace[595492651] 'process raft request' (duration: 118.513879ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T11:57:14.021211Z","caller":"traceutil/trace.go:171","msg":"trace[831464019] linearizableReadLoop","detail":"{readStateIndex:2715; appliedIndex:2714; }","duration":"246.940743ms","start":"2026-05-19T11:57:13.774248Z","end":"2026-05-19T11:57:14.021188Z","steps":["trace[831464019] 'read index received' (duration: 246.758678ms)","trace[831464019] 'applied index is now lower than readState.Index' (duration: 181.215µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T11:57:14.021263Z","caller":"traceutil/trace.go:171","msg":"trace[1816634295] transaction","detail":"{read_only:false; response_revision:2620; number_of_response:1; }","duration":"253.805276ms","start":"2026-05-19T11:57:13.767423Z","end":"2026-05-19T11:57:14.021228Z","steps":["trace[1816634295] 'process raft request' (duration: 253.629261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:57:14.021412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.138179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T11:57:14.021451Z","caller":"traceutil/trace.go:171","msg":"trace[384454270] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2620; }","duration":"247.237321ms","start":"2026-05-19T11:57:13.774201Z","end":"2026-05-19T11:57:14.021439Z","steps":["trace[384454270] 'agreement among raft nodes before linearized reading' (duration: 247.115247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:57:14.021503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.23455ms","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-19T11:57:14.021553Z","caller":"traceutil/trace.go:171","msg":"trace[704493985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2620; }","duration":"247.317782ms","start":"2026-05-19T11:57:13.774223Z","end":"2026-05-19T11:57:14.02154Z","steps":["trace[704493985] 'agreement among raft nodes before linearized reading' (duration: 247.167569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:57:14.021757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.484048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-05-19T11:57:14.021811Z","caller":"traceutil/trace.go:171","msg":"trace[139794386] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2620; }","duration":"247.595481ms","start":"2026-05-19T11:57:13.774204Z","end":"2026-05-19T11:57:14.021799Z","steps":["trace[139794386] 'agreement among raft nodes before linearized reading' (duration: 247.406416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:57:47.106457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.952571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-19T11:57:47.106572Z","caller":"traceutil/trace.go:171","msg":"trace[1844666560] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2801; }","duration":"240.123237ms","start":"2026-05-19T11:57:46.866428Z","end":"2026-05-19T11:57:47.106551Z","steps":["trace[1844666560] 'range keys from in-memory index tree' (duration: 239.701075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:57:47.107298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.805714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T11:57:47.107523Z","caller":"traceutil/trace.go:171","msg":"trace[1420485801] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"222.650324ms","start":"2026-05-19T11:57:46.884838Z","end":"2026-05-19T11:57:47.107489Z","steps":["trace[1420485801] 'process raft request' (duration: 62.580013ms)","trace[1420485801] 'compare' (duration: 159.69207ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T11:57:47.333821Z","caller":"traceutil/trace.go:171","msg":"trace[1439621425] linearizableReadLoop","detail":"{readStateIndex:2907; appliedIndex:2905; }","duration":"113.587712ms","start":"2026-05-19T11:57:47.220212Z","end":"2026-05-19T11:57:47.333799Z","steps":["trace[1439621425] 'read index received' (duration: 93.672419ms)","trace[1439621425] 'applied index is now lower than readState.Index' (duration: 19.914133ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T11:57:47.333932Z","caller":"traceutil/trace.go:171","msg":"trace[399428028] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"224.651222ms","start":"2026-05-19T11:57:47.109269Z","end":"2026-05-19T11:57:47.33392Z","steps":["trace[399428028] 'process raft request' (duration: 204.631916ms)","trace[399428028] 'compare' (duration: 19.413889ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T11:57:47.334557Z","caller":"traceutil/trace.go:171","msg":"trace[1710682901] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"222.802519ms","start":"2026-05-19T11:57:47.111725Z","end":"2026-05-19T11:57:47.334528Z","steps":["trace[1710682901] 'process raft request' (duration: 222.013136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T11:57:47.335005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.772656ms","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-19T11:57:47.335096Z","caller":"traceutil/trace.go:171","msg":"trace[1655432220] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2804; }","duration":"114.91391ms","start":"2026-05-19T11:57:47.220158Z","end":"2026-05-19T11:57:47.335072Z","steps":["trace[1655432220] 'agreement among raft nodes before linearized reading' (duration: 114.638973ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:00:37.823786Z","caller":"traceutil/trace.go:171","msg":"trace[325982808] linearizableReadLoop","detail":"{readStateIndex:3995; appliedIndex:3994; }","duration":"135.504129ms","start":"2026-05-19T12:00:37.688259Z","end":"2026-05-19T12:00:37.823763Z","steps":["trace[325982808] 'read index received' (duration: 132.863644ms)","trace[325982808] 'applied index is now lower than readState.Index' (duration: 2.639445ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T12:00:37.823942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.663213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/thanosrulers.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T12:00:37.823984Z","caller":"traceutil/trace.go:171","msg":"trace[314828716] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/thanosrulers.monitoring.coreos.com; range_end:; response_count:0; response_revision:3850; }","duration":"135.730964ms","start":"2026-05-19T12:00:37.688241Z","end":"2026-05-19T12:00:37.823972Z","steps":["trace[314828716] 'agreement among raft nodes before linearized reading' (duration: 135.655092ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:00:37.823912Z","caller":"traceutil/trace.go:171","msg":"trace[1233261853] transaction","detail":"{read_only:false; response_revision:3850; number_of_response:1; }","duration":"141.063925ms","start":"2026-05-19T12:00:37.682808Z","end":"2026-05-19T12:00:37.823871Z","steps":["trace[1233261853] 'process raft request' (duration: 138.299917ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:00:37.838174Z","caller":"traceutil/trace.go:171","msg":"trace[1416770970] transaction","detail":"{read_only:false; response_revision:3851; number_of_response:1; }","duration":"148.643102ms","start":"2026-05-19T12:00:37.689521Z","end":"2026-05-19T12:00:37.838164Z","steps":["trace[1416770970] 'process raft request' (duration: 148.431297ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:00:37.838398Z","caller":"traceutil/trace.go:171","msg":"trace[786158658] transaction","detail":"{read_only:false; response_revision:3852; number_of_response:1; }","duration":"135.258362ms","start":"2026-05-19T12:00:37.703132Z","end":"2026-05-19T12:00:37.83839Z","steps":["trace[786158658] 'process raft request' (duration: 134.962295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:00:37.838717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.568081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-19T12:00:37.83879Z","caller":"traceutil/trace.go:171","msg":"trace[1608004864] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3852; }","duration":"120.701244ms","start":"2026-05-19T12:00:37.71807Z","end":"2026-05-19T12:00:37.838771Z","steps":["trace[1608004864] 'agreement among raft nodes before linearized reading' (duration: 120.54042ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:01:42.887534Z","caller":"traceutil/trace.go:171","msg":"trace[717703972] linearizableReadLoop","detail":"{readStateIndex:4991; appliedIndex:4990; }","duration":"100.112558ms","start":"2026-05-19T12:01:42.787394Z","end":"2026-05-19T12:01:42.887507Z","steps":["trace[717703972] 'read index received' (duration: 99.949284ms)","trace[717703972] 'applied index is now lower than readState.Index' (duration: 162.114µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T12:01:42.88767Z","caller":"traceutil/trace.go:171","msg":"trace[867792827] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"103.548688ms","start":"2026-05-19T12:01:42.784106Z","end":"2026-05-19T12:01:42.887654Z","steps":["trace[867792827] 'process raft request' (duration: 103.230309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:01:42.888803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.402201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2351250"} {"level":"info","ts":"2026-05-19T12:01:42.888852Z","caller":"traceutil/trace.go:171","msg":"trace[673191087] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4808; }","duration":"101.517224ms","start":"2026-05-19T12:01:42.787322Z","end":"2026-05-19T12:01:42.888839Z","steps":["trace[673191087] 'agreement among raft nodes before linearized reading' (duration: 100.465147ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:02:25.804096Z","caller":"traceutil/trace.go:171","msg":"trace[1574369237] linearizableReadLoop","detail":"{readStateIndex:5374; appliedIndex:5373; }","duration":"154.624375ms","start":"2026-05-19T12:02:25.649449Z","end":"2026-05-19T12:02:25.804074Z","steps":["trace[1574369237] 'read index received' (duration: 154.45164ms)","trace[1574369237] 'applied index is now lower than readState.Index' (duration: 171.765µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T12:02:25.804485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.909614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T12:02:25.804509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.037817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-19T12:02:25.804512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.480328ms","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-19T12:02:25.804572Z","caller":"traceutil/trace.go:171","msg":"trace[1201079082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5179; }","duration":"155.136419ms","start":"2026-05-19T12:02:25.649421Z","end":"2026-05-19T12:02:25.804558Z","steps":["trace[1201079082] 'agreement among raft nodes before linearized reading' (duration: 154.834861ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:02:25.804551Z","caller":"traceutil/trace.go:171","msg":"trace[1168235543] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"179.857925ms","start":"2026-05-19T12:02:25.62464Z","end":"2026-05-19T12:02:25.804498Z","steps":["trace[1168235543] 'process raft request' (duration: 179.29968ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:02:25.804588Z","caller":"traceutil/trace.go:171","msg":"trace[630225575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5179; }","duration":"152.585551ms","start":"2026-05-19T12:02:25.651985Z","end":"2026-05-19T12:02:25.80457Z","steps":["trace[630225575] 'agreement among raft nodes before linearized reading' (duration: 152.362185ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:02:25.804534Z","caller":"traceutil/trace.go:171","msg":"trace[1410453278] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:5179; }","duration":"116.976766ms","start":"2026-05-19T12:02:25.687545Z","end":"2026-05-19T12:02:25.804522Z","steps":["trace[1410453278] 'agreement among raft nodes before linearized reading' (duration: 116.810931ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:02:27.163276Z","caller":"traceutil/trace.go:171","msg":"trace[334107219] linearizableReadLoop","detail":"{readStateIndex:5476; appliedIndex:5474; }","duration":"174.967829ms","start":"2026-05-19T12:02:26.988279Z","end":"2026-05-19T12:02:27.163247Z","steps":["trace[334107219] 'read index received' (duration: 65.772758ms)","trace[334107219] 'applied index is now lower than readState.Index' (duration: 109.193961ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T12:02:27.163546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.250027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-05-19T12:02:27.163517Z","caller":"traceutil/trace.go:171","msg":"trace[864348818] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"179.474258ms","start":"2026-05-19T12:02:26.984019Z","end":"2026-05-19T12:02:27.163493Z","steps":["trace[864348818] 'process raft request' (duration: 157.899035ms)","trace[864348818] 'compare' (duration: 21.187533ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T12:02:27.163596Z","caller":"traceutil/trace.go:171","msg":"trace[1062060079] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5280; }","duration":"175.353709ms","start":"2026-05-19T12:02:26.988226Z","end":"2026-05-19T12:02:27.163579Z","steps":["trace[1062060079] 'agreement among raft nodes before linearized reading' (duration: 175.141393ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:03:13.865522Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2945} {"level":"info","ts":"2026-05-19T12:03:13.930644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2945,"took":"63.66371ms","hash":4015877717,"current-db-size-bytes":52707328,"current-db-size":"53 MB","current-db-size-in-use-bytes":29089792,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T12:03:13.93072Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4015877717,"revision":2945,"compact-revision":-1} {"level":"warn","ts":"2026-05-19T12:06:43.682928Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:44.183411Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:44.684594Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:45.185138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:45.414235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000642938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-19T12:06:45.414406Z","caller":"traceutil/trace.go:171","msg":"trace[1130391211] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000879402s","start":"2026-05-19T12:06:43.413495Z","end":"2026-05-19T12:06:45.414374Z","steps":["trace[1130391211] 'agreement among raft nodes before linearized reading' (duration: 2.000675079s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:45.414489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:43.413478Z","time spent":"2.000996493s","remote":"127.0.0.1:51268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/19 12:06:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T12:06:45.685982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:46.186344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:46.687369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:47.188246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:47.422267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000245241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-19T12:06:47.422371Z","caller":"traceutil/trace.go:171","msg":"trace[2051920159] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000428495s","start":"2026-05-19T12:06:45.421915Z","end":"2026-05-19T12:06:47.422344Z","steps":["trace[2051920159] 'agreement among raft nodes before linearized reading' (duration: 2.000304493s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:47.422557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:45.421888Z","time spent":"2.000594648s","remote":"127.0.0.1:51254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/19 12:06:47 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T12:06:47.689387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:48.169149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:43.170513Z","time spent":"4.998627118s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/19 12:06:48 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T12:06:48.189869Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:48.69197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:49.192859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:49.241861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99978484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-19T12:06:49.242068Z","caller":"traceutil/trace.go:171","msg":"trace[618674167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000062376s","start":"2026-05-19T12:06:44.24197Z","end":"2026-05-19T12:06:49.242033Z","steps":["trace[618674167] 'agreement among raft nodes before linearized reading' (duration: 4.999834462s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:49.242151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:44.241916Z","time spent":"5.000215969s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/05/19 12:06:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T12:06:49.339209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999126971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-19T12:06:49.339336Z","caller":"traceutil/trace.go:171","msg":"trace[573237507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999362195s","start":"2026-05-19T12:06:44.339935Z","end":"2026-05-19T12:06:49.339298Z","steps":["trace[573237507] 'agreement among raft nodes before linearized reading' (duration: 4.999187001s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:49.339402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:44.339912Z","time spent":"4.999477107s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/05/19 12:06:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T12:06:49.422834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000235786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-19T12:06:49.422912Z","caller":"traceutil/trace.go:171","msg":"trace[739633037] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000387659s","start":"2026-05-19T12:06:47.422506Z","end":"2026-05-19T12:06:49.422894Z","steps":["trace[739633037] 'agreement among raft nodes before linearized reading' (duration: 2.000290627s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:49.422962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:47.422479Z","time spent":"2.000473232s","remote":"127.0.0.1:51268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/19 12:06:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T12:06:49.553156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998835917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-19T12:06:49.553285Z","caller":"traceutil/trace.go:171","msg":"trace[2073829267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.99900096s","start":"2026-05-19T12:06:44.554228Z","end":"2026-05-19T12:06:49.553229Z","steps":["trace[2073829267] 'agreement among raft nodes before linearized reading' (duration: 4.998888048s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:49.553371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:44.554202Z","time spent":"4.999130353s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/05/19 12:06:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T12:06:49.693806Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258745879765558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T12:06:49.767079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:44.768798Z","time spent":"4.99827312s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/19 12:06:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T12:06:50.10021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:43.099616Z","time spent":"7.00058621s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-19T12:06:50.101043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:43.100753Z","time spent":"7.000285875s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-19T12:06:50.183285Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-05-19T12:06:50.183501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000623977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-05-19T12:06:50.183579Z","caller":"traceutil/trace.go:171","msg":"trace[1776623045] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; }","duration":"7.00077491s","start":"2026-05-19T12:06:43.182765Z","end":"2026-05-19T12:06:50.18354Z","steps":["trace[1776623045] 'agreement among raft nodes before linearized reading' (duration: 7.000663457s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.183733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:43.182752Z","time spent":"7.000940453s","remote":"127.0.0.1:36426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":0,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:06:50.318736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:43.317676Z","time spent":"7.001051184s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-19T12:06:50.379131Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"7.279353702s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T12:06:50.382758Z","caller":"traceutil/trace.go:171","msg":"trace[1492421648] linearizableReadLoop","detail":"{readStateIndex:7180; appliedIndex:7173; }","duration":"199.359789ms","start":"2026-05-19T12:06:50.183367Z","end":"2026-05-19T12:06:50.382727Z","steps":["trace[1492421648] 'read index received' (duration: 196.517464ms)","trace[1492421648] 'applied index is now lower than readState.Index' (duration: 2.841085ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T12:06:50.382953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.900345774s","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-19T12:06:50.382997Z","caller":"traceutil/trace.go:171","msg":"trace[1682355292] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6924; }","duration":"6.900460647s","start":"2026-05-19T12:06:43.482525Z","end":"2026-05-19T12:06:50.382986Z","steps":["trace[1682355292] 'agreement among raft nodes before linearized reading' (duration: 6.900288904s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.383038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:43.482506Z","time spent":"6.900522188s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-19T12:06:50.383264Z","caller":"traceutil/trace.go:171","msg":"trace[141016462] transaction","detail":"{read_only:false; response_revision:6923; number_of_response:1; }","duration":"5.267234292s","start":"2026-05-19T12:06:45.116019Z","end":"2026-05-19T12:06:50.383253Z","steps":["trace[141016462] 'process raft request' (duration: 5.266501968s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.383378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:45.11599Z","time spent":"5.267307614s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T12:06:50.383537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.24494396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T12:06:50.383578Z","caller":"traceutil/trace.go:171","msg":"trace[857823373] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:6924; }","duration":"4.244996431s","start":"2026-05-19T12:06:46.13857Z","end":"2026-05-19T12:06:50.383567Z","steps":["trace[857823373] 'agreement among raft nodes before linearized reading' (duration: 4.244932609s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.383613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:46.138564Z","time spent":"4.245041992s","remote":"127.0.0.1:36352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:06:50.383754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.831104676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-19T12:06:50.383824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.379119918s","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-19T12:06:50.383864Z","caller":"traceutil/trace.go:171","msg":"trace[50074611] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6924; }","duration":"6.379211209s","start":"2026-05-19T12:06:44.004644Z","end":"2026-05-19T12:06:50.383856Z","steps":["trace[50074611] 'agreement among raft nodes before linearized reading' (duration: 6.379107858s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.383829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.913909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T12:06:50.383902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:44.004616Z","time spent":"6.379280059s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-19T12:06:50.38394Z","caller":"traceutil/trace.go:171","msg":"trace[835118723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6924; }","duration":"953.119402ms","start":"2026-05-19T12:06:49.430793Z","end":"2026-05-19T12:06:50.383912Z","steps":["trace[835118723] 'agreement among raft nodes before linearized reading' (duration: 952.891787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.383918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:45.418018Z","time spent":"4.965885459s","remote":"127.0.0.1:51320","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-19T12:06:50.384014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:49.430766Z","time spent":"953.239205ms","remote":"127.0.0.1:51254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T12:06:50.384046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.453530976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T12:06:50.383983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.42897628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T12:06:50.384085Z","caller":"traceutil/trace.go:171","msg":"trace[22181260] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:6924; }","duration":"6.453621308s","start":"2026-05-19T12:06:43.930455Z","end":"2026-05-19T12:06:50.384077Z","steps":["trace[22181260] 'agreement among raft nodes before linearized reading' (duration: 6.453558386s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.384122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:43.930434Z","time spent":"6.453675588s","remote":"127.0.0.1:52786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:06:50.384104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.974748004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T12:06:50.384127Z","caller":"traceutil/trace.go:171","msg":"trace[1440653408] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:6924; }","duration":"2.429244895s","start":"2026-05-19T12:06:47.95486Z","end":"2026-05-19T12:06:50.384105Z","steps":["trace[1440653408] 'agreement among raft nodes before linearized reading' (duration: 2.42895568s)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:06:50.384193Z","caller":"traceutil/trace.go:171","msg":"trace[406365248] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:6924; }","duration":"2.974940117s","start":"2026-05-19T12:06:47.409244Z","end":"2026-05-19T12:06:50.384184Z","steps":["trace[406365248] 'agreement among raft nodes before linearized reading' (duration: 2.974741273s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.384249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:47.954818Z","time spent":"2.429390398s","remote":"127.0.0.1:36402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:06:50.384342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.495106864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4840"} {"level":"warn","ts":"2026-05-19T12:06:50.383912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.356366559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4840"} {"level":"warn","ts":"2026-05-19T12:06:50.384557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.212411093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-05-19T12:06:50.384599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.57427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:551"} {"level":"info","ts":"2026-05-19T12:06:50.384646Z","caller":"traceutil/trace.go:171","msg":"trace[1168237487] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:6924; }","duration":"616.678642ms","start":"2026-05-19T12:06:49.767956Z","end":"2026-05-19T12:06:50.384634Z","steps":["trace[1168237487] 'agreement among raft nodes before linearized reading' (duration: 616.590411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.384707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:49.76792Z","time spent":"616.777624ms","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":573,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-05-19T12:06:50.384278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:47.40922Z","time spent":"2.97504406s","remote":"127.0.0.1:36368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"info","ts":"2026-05-19T12:06:50.383893Z","caller":"traceutil/trace.go:171","msg":"trace[733855151] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6924; }","duration":"3.831257699s","start":"2026-05-19T12:06:46.552557Z","end":"2026-05-19T12:06:50.383815Z","steps":["trace[733855151] 'agreement among raft nodes before linearized reading' (duration: 3.83083119s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.384993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:46.552534Z","time spent":"3.832445473s","remote":"127.0.0.1:51442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-19T12:06:50.385114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.211729348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T12:06:50.385164Z","caller":"traceutil/trace.go:171","msg":"trace[1362258524] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:6924; }","duration":"6.211848201s","start":"2026-05-19T12:06:44.173304Z","end":"2026-05-19T12:06:50.385152Z","steps":["trace[1362258524] 'agreement among raft nodes before linearized reading' (duration: 6.211523844s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.386483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:44.17328Z","time spent":"6.213182757s","remote":"127.0.0.1:51490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":9,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-05-19T12:06:50.384381Z","caller":"traceutil/trace.go:171","msg":"trace[2092679940] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6924; }","duration":"6.495194006s","start":"2026-05-19T12:06:43.889179Z","end":"2026-05-19T12:06:50.384373Z","steps":["trace[2092679940] 'agreement among raft nodes before linearized reading' (duration: 6.495077534s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.387473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:43.889153Z","time spent":"6.498308316s","remote":"127.0.0.1:51490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4862,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-05-19T12:06:50.385171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.007254616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T12:06:50.387863Z","caller":"traceutil/trace.go:171","msg":"trace[54962568] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:6924; }","duration":"6.010153182s","start":"2026-05-19T12:06:44.377675Z","end":"2026-05-19T12:06:50.387829Z","steps":["trace[54962568] 'agreement among raft nodes before linearized reading' (duration: 6.006849008s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.387901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:44.377603Z","time spent":"6.010285705s","remote":"127.0.0.1:39836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:06:50.384444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.659265059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T12:06:50.388119Z","caller":"traceutil/trace.go:171","msg":"trace[1439079951] range","detail":"{range_begin:/registry/masterleases/199.19.213.157; range_end:; response_count:1; response_revision:6924; }","duration":"3.662951821s","start":"2026-05-19T12:06:46.725136Z","end":"2026-05-19T12:06:50.388088Z","steps":["trace[1439079951] 'agreement among raft nodes before linearized reading' (duration: 3.659161698s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.388155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:46.725117Z","time spent":"3.663028794s","remote":"127.0.0.1:51276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.157\" "} {"level":"warn","ts":"2026-05-19T12:06:50.384452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.22961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T12:06:50.38836Z","caller":"traceutil/trace.go:171","msg":"trace[418629444] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:6924; }","duration":"351.146246ms","start":"2026-05-19T12:06:50.037205Z","end":"2026-05-19T12:06:50.388351Z","steps":["trace[418629444] 'agreement among raft nodes before linearized reading' (duration: 347.189189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.388528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:50.037167Z","time spent":"351.330639ms","remote":"127.0.0.1:51722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":6,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:06:50.384489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.772034336s","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:5"} {"level":"warn","ts":"2026-05-19T12:06:50.384522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.172754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T12:06:50.389736Z","caller":"traceutil/trace.go:171","msg":"trace[1325992113] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:6924; }","duration":"492.445185ms","start":"2026-05-19T12:06:49.897273Z","end":"2026-05-19T12:06:50.389718Z","steps":["trace[1325992113] 'agreement among raft nodes before linearized reading' (duration: 487.208033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.389791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:49.897244Z","time spent":"492.530157ms","remote":"127.0.0.1:39794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-19T12:06:50.38459Z","caller":"traceutil/trace.go:171","msg":"trace[113216474] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6924; }","duration":"6.357080342s","start":"2026-05-19T12:06:44.027491Z","end":"2026-05-19T12:06:50.384572Z","steps":["trace[113216474] 'agreement among raft nodes before linearized reading' (duration: 6.356265367s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.390158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:44.02747Z","time spent":"6.362403016s","remote":"127.0.0.1:51490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4862,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"info","ts":"2026-05-19T12:06:50.384582Z","caller":"traceutil/trace.go:171","msg":"trace[524033329] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:6924; }","duration":"2.212483993s","start":"2026-05-19T12:06:48.172092Z","end":"2026-05-19T12:06:50.384576Z","steps":["trace[524033329] 'agreement among raft nodes before linearized reading' (duration: 2.212434423s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.390365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:48.172068Z","time spent":"2.218289936s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":546,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-05-19T12:06:50.384329Z","caller":"traceutil/trace.go:171","msg":"trace[1961609697] transaction","detail":"{read_only:false; response_revision:6924; number_of_response:1; }","duration":"1.007746302s","start":"2026-05-19T12:06:49.376553Z","end":"2026-05-19T12:06:50.384299Z","steps":["trace[1961609697] 'process raft request' (duration: 1.00608197s)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:06:50.39104Z","caller":"traceutil/trace.go:171","msg":"trace[722126386] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:6924; }","duration":"3.776303979s","start":"2026-05-19T12:06:46.612429Z","end":"2026-05-19T12:06:50.388733Z","steps":["trace[722126386] 'agreement among raft nodes before linearized reading' (duration: 3.772036037s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.391107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:46.612417Z","time spent":"3.778654915s","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:06:50.384944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.95185229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T12:06:50.391219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:49.376523Z","time spent":"1.014544373s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T12:06:50.391243Z","caller":"traceutil/trace.go:171","msg":"trace[1430359011] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:6924; }","duration":"1.958190653s","start":"2026-05-19T12:06:48.433038Z","end":"2026-05-19T12:06:50.391228Z","steps":["trace[1430359011] 'agreement among raft nodes before linearized reading' (duration: 1.951799719s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.391285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:48.433013Z","time spent":"1.958259034s","remote":"127.0.0.1:51442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":45,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:06:50.385208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.600470353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T12:06:50.39141Z","caller":"traceutil/trace.go:171","msg":"trace[1479916129] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:6924; }","duration":"5.606713955s","start":"2026-05-19T12:06:44.784669Z","end":"2026-05-19T12:06:50.391383Z","steps":["trace[1479916129] 'agreement among raft nodes before linearized reading' (duration: 5.600521495s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.391447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:44.78458Z","time spent":"5.606856477s","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:06:50.385263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.292138314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T12:06:50.391669Z","caller":"traceutil/trace.go:171","msg":"trace[1376044762] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6924; }","duration":"6.29859914s","start":"2026-05-19T12:06:44.093055Z","end":"2026-05-19T12:06:50.391654Z","steps":["trace[1376044762] 'agreement among raft nodes before linearized reading' (duration: 6.292183126s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.391712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:44.093024Z","time spent":"6.298674531s","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:06:50.385288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.261413228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T12:06:50.391819Z","caller":"traceutil/trace.go:171","msg":"trace[1698013942] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:6924; }","duration":"4.267981233s","start":"2026-05-19T12:06:46.123807Z","end":"2026-05-19T12:06:50.391788Z","steps":["trace[1698013942] 'agreement among raft nodes before linearized reading' (duration: 4.261440547s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.391858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:46.123723Z","time spent":"4.268124227s","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":29,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:06:50.385343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.296401916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4840"} {"level":"warn","ts":"2026-05-19T12:06:50.384422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.644665587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T12:06:50.386172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.546122825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T12:06:50.392005Z","caller":"traceutil/trace.go:171","msg":"trace[32476347] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:6924; }","duration":"4.553045178s","start":"2026-05-19T12:06:45.838949Z","end":"2026-05-19T12:06:50.391994Z","steps":["trace[32476347] 'agreement among raft nodes before linearized reading' (duration: 4.546150155s)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:06:50.392069Z","caller":"traceutil/trace.go:171","msg":"trace[688744438] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6924; }","duration":"6.303182668s","start":"2026-05-19T12:06:44.08888Z","end":"2026-05-19T12:06:50.392062Z","steps":["trace[688744438] 'agreement among raft nodes before linearized reading' (duration: 6.296365906s)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:06:50.392116Z","caller":"traceutil/trace.go:171","msg":"trace[1875021541] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:6924; }","duration":"6.652391257s","start":"2026-05-19T12:06:43.739704Z","end":"2026-05-19T12:06:50.392096Z","steps":["trace[1875021541] 'agreement among raft nodes before linearized reading' (duration: 6.644694997s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:06:50.39214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:44.088851Z","time spent":"6.30324604s","remote":"127.0.0.1:51490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4862,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-05-19T12:06:50.392158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:43.739683Z","time spent":"6.652462568s","remote":"127.0.0.1:36366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:06:50.392203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:06:45.838929Z","time spent":"4.553100909s","remote":"127.0.0.1:51752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-19T12:07:05.706161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.901769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T12:07:05.706286Z","caller":"traceutil/trace.go:171","msg":"trace[1411709312] linearizableReadLoop","detail":"{readStateIndex:7266; appliedIndex:7264; }","duration":"293.494334ms","start":"2026-05-19T12:07:05.412771Z","end":"2026-05-19T12:07:05.706265Z","steps":["trace[1411709312] 'read index received' (duration: 7.092501ms)","trace[1411709312] 'applied index is now lower than readState.Index' (duration: 286.399963ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T12:07:05.706428Z","caller":"traceutil/trace.go:171","msg":"trace[922731835] transaction","detail":"{read_only:false; response_revision:7006; number_of_response:1; }","duration":"302.321859ms","start":"2026-05-19T12:07:05.404063Z","end":"2026-05-19T12:07:05.706385Z","steps":["trace[922731835] 'process raft request' (duration: 110.081743ms)","trace[922731835] 'compare' (duration: 191.546041ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T12:07:05.706607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:07:05.404035Z","time spent":"302.481312ms","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T12:07:05.706627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.861371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T12:07:05.706712Z","caller":"traceutil/trace.go:171","msg":"trace[1391530946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7006; }","duration":"294.003634ms","start":"2026-05-19T12:07:05.412695Z","end":"2026-05-19T12:07:05.706699Z","steps":["trace[1391530946] 'agreement among raft nodes before linearized reading' (duration: 293.850671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:07:05.888518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.930062ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-05-19T12:07:05.889294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.787266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T12:07:05.889356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.706625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-19T12:07:05.889444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.116995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T12:07:05.889384Z","caller":"traceutil/trace.go:171","msg":"trace[1401045340] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:7006; }","duration":"258.898129ms","start":"2026-05-19T12:07:05.630467Z","end":"2026-05-19T12:07:05.889365Z","steps":["trace[1401045340] 'agreement among raft nodes before linearized reading' (duration: 258.679405ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:07:05.889529Z","caller":"traceutil/trace.go:171","msg":"trace[1761199744] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7006; }","duration":"255.854688ms","start":"2026-05-19T12:07:05.633585Z","end":"2026-05-19T12:07:05.88944Z","steps":["trace[1761199744] 'agreement among raft nodes before linearized reading' (duration: 255.543831ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:07:05.889563Z","caller":"traceutil/trace.go:171","msg":"trace[2055505705] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7006; }","duration":"443.252207ms","start":"2026-05-19T12:07:05.446265Z","end":"2026-05-19T12:07:05.889517Z","steps":["trace[2055505705] 'agreement among raft nodes before linearized reading' (duration: 443.000552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:07:05.889614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:07:05.446249Z","time spent":"443.355329ms","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T12:07:05.889381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.163516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T12:07:05.889136Z","caller":"traceutil/trace.go:171","msg":"trace[1331172147] linearizableReadLoop","detail":"{readStateIndex:7267; appliedIndex:7266; }","duration":"182.779019ms","start":"2026-05-19T12:07:05.706326Z","end":"2026-05-19T12:07:05.889105Z","steps":["trace[1331172147] 'read index received' (duration: 75.192803ms)","trace[1331172147] 'applied index is now lower than readState.Index' (duration: 107.582936ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T12:07:05.890176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.224288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T12:07:05.890215Z","caller":"traceutil/trace.go:171","msg":"trace[1506164882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7006; }","duration":"182.32085ms","start":"2026-05-19T12:07:05.707882Z","end":"2026-05-19T12:07:05.890203Z","steps":["trace[1506164882] 'agreement among raft nodes before linearized reading' (duration: 182.257848ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:07:05.890024Z","caller":"traceutil/trace.go:171","msg":"trace[1344341932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7006; }","duration":"242.624825ms","start":"2026-05-19T12:07:05.647169Z","end":"2026-05-19T12:07:05.889794Z","steps":["trace[1344341932] 'agreement among raft nodes before linearized reading' (duration: 242.000983ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:07:06.060189Z","caller":"traceutil/trace.go:171","msg":"trace[319050374] transaction","detail":"{read_only:false; response_revision:7007; number_of_response:1; }","duration":"164.090488ms","start":"2026-05-19T12:07:05.896066Z","end":"2026-05-19T12:07:06.060157Z","steps":["trace[319050374] 'process raft request' (duration: 163.915255ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:07:06.201175Z","caller":"traceutil/trace.go:171","msg":"trace[271162436] transaction","detail":"{read_only:false; response_revision:7008; number_of_response:1; }","duration":"304.847996ms","start":"2026-05-19T12:07:05.896213Z","end":"2026-05-19T12:07:06.201061Z","steps":["trace[271162436] 'process raft request' (duration: 220.899578ms)","trace[271162436] 'compare' (duration: 83.420858ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T12:07:06.201201Z","caller":"traceutil/trace.go:171","msg":"trace[1726052398] linearizableReadLoop","detail":"{readStateIndex:7269; appliedIndex:7267; }","duration":"304.428517ms","start":"2026-05-19T12:07:05.896365Z","end":"2026-05-19T12:07:06.200793Z","steps":["trace[1726052398] 'read index received' (duration: 163.631249ms)","trace[1726052398] 'applied index is now lower than readState.Index' (duration: 140.795958ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T12:07:06.201375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:07:05.896186Z","time spent":"305.078ms","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T12:07:06.202006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.619411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:12170"} {"level":"info","ts":"2026-05-19T12:07:06.202059Z","caller":"traceutil/trace.go:171","msg":"trace[184907118] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:7008; }","duration":"305.707022ms","start":"2026-05-19T12:07:05.896339Z","end":"2026-05-19T12:07:06.202046Z","steps":["trace[184907118] 'agreement among raft nodes before linearized reading' (duration: 304.795395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T12:07:06.202141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T12:07:05.896323Z","time spent":"305.803354ms","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":12192,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-19T12:07:06.561662Z","caller":"traceutil/trace.go:171","msg":"trace[290056235] transaction","detail":"{read_only:false; response_revision:7010; number_of_response:1; }","duration":"113.246781ms","start":"2026-05-19T12:07:06.448386Z","end":"2026-05-19T12:07:06.561633Z","steps":["trace[290056235] 'process raft request' (duration: 25.861664ms)","trace[290056235] 'compare' (duration: 87.251624ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T12:07:06.76158Z","caller":"traceutil/trace.go:171","msg":"trace[1409296218] transaction","detail":"{read_only:false; response_revision:7013; number_of_response:1; }","duration":"135.665325ms","start":"2026-05-19T12:07:06.625887Z","end":"2026-05-19T12:07:06.761552Z","steps":["trace[1409296218] 'process raft request' (duration: 108.414953ms)","trace[1409296218] 'compare' (duration: 26.847774ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T12:07:06.796065Z","caller":"traceutil/trace.go:171","msg":"trace[1572241015] transaction","detail":"{read_only:false; response_revision:7014; number_of_response:1; }","duration":"170.048139ms","start":"2026-05-19T12:07:06.625992Z","end":"2026-05-19T12:07:06.79604Z","steps":["trace[1572241015] 'process raft request' (duration: 169.839165ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T12:07:06.954815Z","caller":"traceutil/trace.go:171","msg":"trace[83062721] transaction","detail":"{read_only:false; response_revision:7015; number_of_response:1; }","duration":"103.1605ms","start":"2026-05-19T12:07:06.851609Z","end":"2026-05-19T12:07:06.954769Z","steps":["trace[83062721] 'process raft request' (duration: 51.144847ms)","trace[83062721] 'compare' (duration: 51.017833ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T12:08:13.872724Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5552} {"level":"info","ts":"2026-05-19T12:08:13.946966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5552,"took":"72.393949ms","hash":975346900,"current-db-size-bytes":52707328,"current-db-size":"53 MB","current-db-size-in-use-bytes":18415616,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-19T12:08:13.947068Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":975346900,"revision":5552,"compact-revision":2945}