{"level":"warn","ts":"2026-05-14T13:13:58.790172Z","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-14T13:13:58.790246Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-14T13:13:58.790302Z","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-14T13:13:58.790309Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-05-14T13:13:58.790324Z","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-14T13:13:58.790939Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-05-14T13:13:58.791042Z","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.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-14T13:13:58.797363Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.839792ms"} {"level":"info","ts":"2026-05-14T13:13:58.80478Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-05-14T13:13:58.804848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T13:13:58.804867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-05-14T13:13:58.804879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T13:13:58.804891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-05-14T13:13:58.80493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-05-14T13:13:58.813917Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T13:13:58.816855Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T13:13:58.821208Z","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-14T13:13:58.823549Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T13:13:58.823697Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T13:13:58.823691Z","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-14T13:13:58.823876Z","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-14T13:13:58.823904Z","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-14T13:13:58.825711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-05-14T13:13:58.825956Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-05-14T13:13:58.829418Z","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-14T13:13:58.829572Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-05-14T13:13:58.829605Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-05-14T13:13:58.829749Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T13:13:58.829829Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T13:13:59.005841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T13:13:59.005976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T13:13:59.006012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-05-14T13:13:59.006049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-05-14T13:13:59.006075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-05-14T13:13:59.006094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-05-14T13:13:59.006112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-05-14T13:13:59.009229Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T13:13:59.009278Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T13:13:59.009846Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T13:13:59.009965Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T13:13:59.010054Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T13:13:59.012142Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T13:13:59.012968Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T13:13:59.018196Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T13:13:59.018281Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T13:13:59.018308Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T13:13:59.028372Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"warn","ts":"2026-05-14T13:14:02.18633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.565055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-14T13:14:02.186443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.277168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:14:02.186474Z","caller":"traceutil/trace.go:171","msg":"trace[469297467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"671.800938ms","start":"2026-05-14T13:14:01.514646Z","end":"2026-05-14T13:14:02.186447Z","steps":["trace[469297467] 'range keys from in-memory index tree' (duration: 671.480514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:02.186341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.387294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:14:02.186563Z","caller":"traceutil/trace.go:171","msg":"trace[1983210195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"721.40776ms","start":"2026-05-14T13:14:01.465105Z","end":"2026-05-14T13:14:02.186512Z","steps":["trace[1983210195] 'range keys from in-memory index tree' (duration: 721.173597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:02.186536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:01.514627Z","time spent":"671.898949ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T13:14:02.186608Z","caller":"traceutil/trace.go:171","msg":"trace[1876332385] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:73; }","duration":"775.729978ms","start":"2026-05-14T13:14:01.410856Z","end":"2026-05-14T13:14:02.186586Z","steps":["trace[1876332385] 'range keys from in-memory index tree' (duration: 775.146271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:02.186611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:01.465094Z","time spent":"721.508131ms","remote":"127.0.0.1:45886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T13:14:02.186662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:01.410828Z","time spent":"775.821359ms","remote":"127.0.0.1:46172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-05-14T13:14:02.187028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.983401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:14:02.187309Z","caller":"traceutil/trace.go:171","msg":"trace[1737929336] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:73; }","duration":"776.283265ms","start":"2026-05-14T13:14:01.410907Z","end":"2026-05-14T13:14:02.187191Z","steps":["trace[1737929336] 'range keys from in-memory index tree' (duration: 775.873579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:02.187827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:01.410885Z","time spent":"776.922773ms","remote":"127.0.0.1:46178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-05-14T13:14:02.316931Z","caller":"traceutil/trace.go:171","msg":"trace[349504040] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:78; }","duration":"113.411437ms","start":"2026-05-14T13:14:02.203486Z","end":"2026-05-14T13:14:02.316898Z","steps":["trace[349504040] 'read index received' (duration: 112.184913ms)","trace[349504040] 'applied index is now lower than readState.Index' (duration: 1.224844ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:14:02.316946Z","caller":"traceutil/trace.go:171","msg":"trace[472810916] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"125.648999ms","start":"2026-05-14T13:14:02.191273Z","end":"2026-05-14T13:14:02.316922Z","steps":["trace[472810916] 'process raft request' (duration: 124.320673ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:02.317016Z","caller":"traceutil/trace.go:171","msg":"trace[413236616] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"116.873677ms","start":"2026-05-14T13:14:02.2001Z","end":"2026-05-14T13:14:02.316973Z","steps":["trace[413236616] 'process raft request' (duration: 116.701185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:02.3174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.868482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T13:14:02.3175Z","caller":"traceutil/trace.go:171","msg":"trace[1881850941] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:75; }","duration":"114.027435ms","start":"2026-05-14T13:14:02.203447Z","end":"2026-05-14T13:14:02.317474Z","steps":["trace[1881850941] 'agreement among raft nodes before linearized reading' (duration: 113.60664ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:28.633748Z","caller":"traceutil/trace.go:171","msg":"trace[1215509081] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"620.12081ms","start":"2026-05-14T13:14:28.013598Z","end":"2026-05-14T13:14:28.633719Z","steps":["trace[1215509081] 'process raft request' (duration: 619.918078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:28.634325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.013579Z","time spent":"620.335731ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:14:28.641863Z","caller":"traceutil/trace.go:171","msg":"trace[213911385] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"627.78099ms","start":"2026-05-14T13:14:28.014053Z","end":"2026-05-14T13:14:28.641834Z","steps":["trace[213911385] 'process raft request' (duration: 627.581059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:28.641991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.014048Z","time spent":"627.882901ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:14:28.642015Z","caller":"traceutil/trace.go:171","msg":"trace[1333961285] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"622.609879ms","start":"2026-05-14T13:14:28.019345Z","end":"2026-05-14T13:14:28.641955Z","steps":["trace[1333961285] 'process raft request' (duration: 622.329607ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:28.642077Z","caller":"traceutil/trace.go:171","msg":"trace[1664732264] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"601.101381ms","start":"2026-05-14T13:14:28.040952Z","end":"2026-05-14T13:14:28.642054Z","steps":["trace[1664732264] 'process raft request' (duration: 600.805538ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:28.642099Z","caller":"traceutil/trace.go:171","msg":"trace[1968338832] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"587.053921ms","start":"2026-05-14T13:14:28.055037Z","end":"2026-05-14T13:14:28.642091Z","steps":["trace[1968338832] 'process raft request' (duration: 586.820119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:28.642138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.055031Z","time spent":"587.088351ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:14:28.642143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.040937Z","time spent":"601.173891ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:14:28.642094Z","caller":"traceutil/trace.go:171","msg":"trace[906440965] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"590.159485ms","start":"2026-05-14T13:14:28.051841Z","end":"2026-05-14T13:14:28.642001Z","steps":["trace[906440965] 'process raft request' (duration: 589.978294ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:28.642316Z","caller":"traceutil/trace.go:171","msg":"trace[1337436641] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"551.12271ms","start":"2026-05-14T13:14:28.091187Z","end":"2026-05-14T13:14:28.64231Z","steps":["trace[1337436641] 'process raft request' (duration: 550.889308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:28.642356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.091166Z","time spent":"551.17299ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:14:28.642348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.051833Z","time spent":"590.463587ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:14:28.642208Z","caller":"traceutil/trace.go:171","msg":"trace[992052171] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"573.066462ms","start":"2026-05-14T13:14:28.069134Z","end":"2026-05-14T13:14:28.6422Z","steps":["trace[992052171] 'process raft request' (duration: 572.76766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:28.642434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.069125Z","time spent":"573.288993ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:14:28.642215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.019339Z","time spent":"622.77427ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:14:28.641883Z","caller":"traceutil/trace.go:171","msg":"trace[1600831626] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"627.973931ms","start":"2026-05-14T13:14:28.013892Z","end":"2026-05-14T13:14:28.641866Z","steps":["trace[1600831626] 'process raft request' (duration: 627.642179ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:28.642087Z","caller":"traceutil/trace.go:171","msg":"trace[940511058] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"609.690238ms","start":"2026-05-14T13:14:28.032387Z","end":"2026-05-14T13:14:28.642077Z","steps":["trace[940511058] 'process raft request' (duration: 609.330585ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:28.642513Z","caller":"traceutil/trace.go:171","msg":"trace[398362951] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"465.589491ms","start":"2026-05-14T13:14:28.176914Z","end":"2026-05-14T13:14:28.642504Z","steps":["trace[398362951] 'process raft request' (duration: 465.316449ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:28.642217Z","caller":"traceutil/trace.go:171","msg":"trace[1755080475] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"571.986473ms","start":"2026-05-14T13:14:28.070222Z","end":"2026-05-14T13:14:28.642209Z","steps":["trace[1755080475] 'process raft request' (duration: 571.769861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:28.642605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.070214Z","time spent":"572.373896ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:14:28.642539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.032373Z","time spent":"610.154271ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:14:28.642677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.176894Z","time spent":"465.726982ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:14:28.64255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.013869Z","time spent":"628.657256ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:14:28.64825Z","caller":"traceutil/trace.go:171","msg":"trace[517098444] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:524; }","duration":"461.588579ms","start":"2026-05-14T13:14:28.18664Z","end":"2026-05-14T13:14:28.648229Z","steps":["trace[517098444] 'read index received' (duration: 446.463871ms)","trace[517098444] 'applied index is now lower than readState.Index' (duration: 15.123618ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:14:28.648465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.816211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-14T13:14:28.648556Z","caller":"traceutil/trace.go:171","msg":"trace[249027171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"461.914723ms","start":"2026-05-14T13:14:28.186597Z","end":"2026-05-14T13:14:28.648512Z","steps":["trace[249027171] 'agreement among raft nodes before linearized reading' (duration: 461.772891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:28.648601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.186585Z","time spent":"462.003573ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:14:28.649083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.805704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-14T13:14:28.649141Z","caller":"traceutil/trace.go:171","msg":"trace[55346362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"382.919005ms","start":"2026-05-14T13:14:28.266209Z","end":"2026-05-14T13:14:28.649128Z","steps":["trace[55346362] 'agreement among raft nodes before linearized reading' (duration: 382.760044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:28.64917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:28.266187Z","time spent":"382.977046ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T13:14:33.128454Z","caller":"traceutil/trace.go:171","msg":"trace[1520870873] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"141.357878ms","start":"2026-05-14T13:14:32.987072Z","end":"2026-05-14T13:14:33.12843Z","steps":["trace[1520870873] 'process raft request' (duration: 141.143866ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:33.150367Z","caller":"traceutil/trace.go:171","msg":"trace[1432885492] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"131.015669ms","start":"2026-05-14T13:14:33.019328Z","end":"2026-05-14T13:14:33.150344Z","steps":["trace[1432885492] 'read index received' (duration: 108.952816ms)","trace[1432885492] 'applied index is now lower than readState.Index' (duration: 22.061833ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:14:33.150517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.16446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:14:33.150551Z","caller":"traceutil/trace.go:171","msg":"trace[807778822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"131.23796ms","start":"2026-05-14T13:14:33.019301Z","end":"2026-05-14T13:14:33.150539Z","steps":["trace[807778822] 'agreement among raft nodes before linearized reading' (duration: 131.16594ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:33.150457Z","caller":"traceutil/trace.go:171","msg":"trace[1525230700] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"155.31295ms","start":"2026-05-14T13:14:32.995122Z","end":"2026-05-14T13:14:33.150435Z","steps":["trace[1525230700] 'process raft request' (duration: 154.714715ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:34.363436Z","caller":"traceutil/trace.go:171","msg":"trace[1631475905] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"348.916185ms","start":"2026-05-14T13:14:34.014494Z","end":"2026-05-14T13:14:34.363411Z","steps":["trace[1631475905] 'read index received' (duration: 347.843826ms)","trace[1631475905] 'applied index is now lower than readState.Index' (duration: 1.071389ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:14:34.363623Z","caller":"traceutil/trace.go:171","msg":"trace[208969066] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"767.820122ms","start":"2026-05-14T13:14:33.595644Z","end":"2026-05-14T13:14:34.363464Z","steps":["trace[208969066] 'process raft request' (duration: 767.110046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:34.363685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.146126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:14:34.363903Z","caller":"traceutil/trace.go:171","msg":"trace[395170907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"349.359707ms","start":"2026-05-14T13:14:34.014436Z","end":"2026-05-14T13:14:34.363795Z","steps":["trace[395170907] 'agreement among raft nodes before linearized reading' (duration: 349.089536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:34.363977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:34.014417Z","time spent":"349.54772ms","remote":"127.0.0.1:45886","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-14T13:14:34.364285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:33.595608Z","time spent":"768.142195ms","remote":"127.0.0.1:45956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:14:35.144531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.670974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:14:35.144612Z","caller":"traceutil/trace.go:171","msg":"trace[1505550477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"120.791275ms","start":"2026-05-14T13:14:35.023802Z","end":"2026-05-14T13:14:35.144594Z","steps":["trace[1505550477] 'range keys from in-memory index tree' (duration: 120.613744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:35.632531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.628808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:14:35.632785Z","caller":"traceutil/trace.go:171","msg":"trace[1877079103] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"469.15679ms","start":"2026-05-14T13:14:35.163594Z","end":"2026-05-14T13:14:35.632751Z","steps":["trace[1877079103] 'process raft request' (duration: 346.026008ms)","trace[1877079103] 'compare' (duration: 122.458946ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:14:35.633052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:35.163578Z","time spent":"469.391161ms","remote":"127.0.0.1:46046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:14:35.633366Z","caller":"traceutil/trace.go:171","msg":"trace[1175548521] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"278.472458ms","start":"2026-05-14T13:14:35.354877Z","end":"2026-05-14T13:14:35.633349Z","steps":["trace[1175548521] 'process raft request' (duration: 277.946614ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:14:35.652639Z","caller":"traceutil/trace.go:171","msg":"trace[1368966868] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"109.012739ms","start":"2026-05-14T13:14:35.543583Z","end":"2026-05-14T13:14:35.652596Z","steps":["trace[1368966868] 'process raft request' (duration: 108.856708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:37.188274Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.067016091s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-14T13:14:37.228954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318921391s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:14:37.229072Z","caller":"traceutil/trace.go:171","msg":"trace[604486992] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:621; }","duration":"1.319049143s","start":"2026-05-14T13:14:35.910005Z","end":"2026-05-14T13:14:37.229054Z","steps":["trace[604486992] 'range keys from in-memory index tree' (duration: 1.318905601s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:37.23078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108005923s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:14:37.231099Z","caller":"traceutil/trace.go:171","msg":"trace[1448904097] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"1.216005357s","start":"2026-05-14T13:14:36.015036Z","end":"2026-05-14T13:14:37.231042Z","steps":["trace[1448904097] 'read index received' (duration: 106.312763ms)","trace[1448904097] 'applied index is now lower than readState.Index' (duration: 1.109690144s)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:14:37.231701Z","caller":"traceutil/trace.go:171","msg":"trace[2144441496] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.53256253s","start":"2026-05-14T13:14:35.698663Z","end":"2026-05-14T13:14:37.231225Z","steps":["trace[2144441496] 'process raft request' (duration: 422.675724ms)","trace[2144441496] 'compare' (duration: 1.107808392s)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:14:37.23189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:35.698638Z","time spent":"1.533150654s","remote":"127.0.0.1:46326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:14:37.232257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217208696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:14:37.232333Z","caller":"traceutil/trace.go:171","msg":"trace[1534620036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"1.217326227s","start":"2026-05-14T13:14:36.014984Z","end":"2026-05-14T13:14:37.23231Z","steps":["trace[1534620036] 'agreement among raft nodes before linearized reading' (duration: 1.216176748s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:37.232409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:36.014958Z","time spent":"1.217435628s","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T13:14:37.233183Z","caller":"traceutil/trace.go:171","msg":"trace[1674379238] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.494040451s","start":"2026-05-14T13:14:35.739105Z","end":"2026-05-14T13:14:37.233146Z","steps":["trace[1674379238] 'process raft request' (duration: 1.491799484s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:37.233319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:35.739077Z","time spent":"1.494161691s","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:14:37.235556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.454052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:14:37.235636Z","caller":"traceutil/trace.go:171","msg":"trace[498819961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"529.586342ms","start":"2026-05-14T13:14:36.706031Z","end":"2026-05-14T13:14:37.235617Z","steps":["trace[498819961] 'agreement among raft nodes before linearized reading' (duration: 528.973538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:37.235672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:36.706014Z","time spent":"529.651413ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:14:37.235906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.83475ms","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-14T13:14:37.235943Z","caller":"traceutil/trace.go:171","msg":"trace[393269176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"481.92275ms","start":"2026-05-14T13:14:36.754012Z","end":"2026-05-14T13:14:37.235935Z","steps":["trace[393269176] 'agreement among raft nodes before linearized reading' (duration: 481.82867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:37.236016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.029969ms","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-14T13:14:37.23605Z","caller":"traceutil/trace.go:171","msg":"trace[503073023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"529.110329ms","start":"2026-05-14T13:14:36.706927Z","end":"2026-05-14T13:14:37.236038Z","steps":["trace[503073023] 'agreement among raft nodes before linearized reading' (duration: 529.026599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:37.236077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:36.706898Z","time spent":"529.173139ms","remote":"127.0.0.1:46108","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-14T13:14:37.236074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:36.753962Z","time spent":"482.080031ms","remote":"127.0.0.1:46108","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-14T13:14:52.831844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.157261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-clnxm\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-14T13:14:52.831952Z","caller":"traceutil/trace.go:171","msg":"trace[1983622033] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-clnxm; range_end:; response_count:1; response_revision:785; }","duration":"664.339413ms","start":"2026-05-14T13:14:52.167591Z","end":"2026-05-14T13:14:52.831931Z","steps":["trace[1983622033] 'range keys from in-memory index tree' (duration: 663.895211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:14:52.831995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:14:52.167568Z","time spent":"664.418623ms","remote":"127.0.0.1:46046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-clnxm\" "} {"level":"info","ts":"2026-05-14T13:14:52.950164Z","caller":"traceutil/trace.go:171","msg":"trace[810182642] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"109.80446ms","start":"2026-05-14T13:14:52.840332Z","end":"2026-05-14T13:14:52.950136Z","steps":["trace[810182642] 'process raft request' (duration: 109.639088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:15:46.115173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.348083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:15:46.115431Z","caller":"traceutil/trace.go:171","msg":"trace[1709539231] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"305.342318ms","start":"2026-05-14T13:15:45.810049Z","end":"2026-05-14T13:15:46.115391Z","steps":["trace[1709539231] 'process raft request' (duration: 131.684304ms)","trace[1709539231] 'compare' (duration: 173.009961ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:15:46.115596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:15:45.810033Z","time spent":"305.479592ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:15:46.115813Z","caller":"traceutil/trace.go:171","msg":"trace[51263770] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"302.10931ms","start":"2026-05-14T13:15:45.813672Z","end":"2026-05-14T13:15:46.115781Z","steps":["trace[51263770] 'process raft request' (duration: 301.672504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:15:46.116006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:15:45.813656Z","time spent":"302.274545ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:15:46.115854Z","caller":"traceutil/trace.go:171","msg":"trace[619650050] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"302.67498ms","start":"2026-05-14T13:15:45.813113Z","end":"2026-05-14T13:15:46.115788Z","steps":["trace[619650050] 'process raft request' (duration: 302.166992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:15:46.116451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:15:45.813098Z","time spent":"303.216589ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:15:57.77641Z","caller":"traceutil/trace.go:171","msg":"trace[722523836] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"116.866593ms","start":"2026-05-14T13:15:57.659508Z","end":"2026-05-14T13:15:57.776375Z","steps":["trace[722523836] 'process raft request' (duration: 116.716638ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:03.423728Z","caller":"traceutil/trace.go:171","msg":"trace[632367298] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"183.448403ms","start":"2026-05-14T13:16:03.240263Z","end":"2026-05-14T13:16:03.423711Z","steps":["trace[632367298] 'process raft request' (duration: 183.33557ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:03.580082Z","caller":"traceutil/trace.go:171","msg":"trace[1193118063] linearizableReadLoop","detail":"{readStateIndex:1825; appliedIndex:1823; }","duration":"331.990064ms","start":"2026-05-14T13:16:03.248065Z","end":"2026-05-14T13:16:03.580056Z","steps":["trace[1193118063] 'read index received' (duration: 175.554787ms)","trace[1193118063] 'applied index is now lower than readState.Index' (duration: 156.434237ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:16:03.580428Z","caller":"traceutil/trace.go:171","msg":"trace[950905858] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"337.923951ms","start":"2026-05-14T13:16:03.242472Z","end":"2026-05-14T13:16:03.580396Z","steps":["trace[950905858] 'process raft request' (duration: 256.368283ms)","trace[950905858] 'compare' (duration: 81.117245ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:16:03.580503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.267872ms","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:5"} {"level":"warn","ts":"2026-05-14T13:16:03.580545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:03.242434Z","time spent":"338.047636ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":532,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:16:03.580536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.333602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:03.580587Z","caller":"traceutil/trace.go:171","msg":"trace[1963781249] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"332.388526ms","start":"2026-05-14T13:16:03.248179Z","end":"2026-05-14T13:16:03.580568Z","steps":["trace[1963781249] 'agreement among raft nodes before linearized reading' (duration: 332.238202ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:03.580602Z","caller":"traceutil/trace.go:171","msg":"trace[497373228] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"170.440105ms","start":"2026-05-14T13:16:03.410145Z","end":"2026-05-14T13:16:03.580585Z","steps":["trace[497373228] 'agreement among raft nodes before linearized reading' (duration: 170.306151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.580642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:03.24817Z","time spent":"332.461458ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-14T13:16:03.580667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.581302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:03.580733Z","caller":"traceutil/trace.go:171","msg":"trace[164002124] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"332.689986ms","start":"2026-05-14T13:16:03.248031Z","end":"2026-05-14T13:16:03.580721Z","steps":["trace[164002124] 'agreement among raft nodes before linearized reading' (duration: 332.594223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.580775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:03.248018Z","time spent":"332.744417ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 "} {"level":"warn","ts":"2026-05-14T13:16:03.5808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.286763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:03.580831Z","caller":"traceutil/trace.go:171","msg":"trace[1784027418] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"151.315124ms","start":"2026-05-14T13:16:03.429506Z","end":"2026-05-14T13:16:03.580822Z","steps":["trace[1784027418] 'agreement among raft nodes before linearized reading' (duration: 151.274943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.580857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.694241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:03.580885Z","caller":"traceutil/trace.go:171","msg":"trace[2132490089] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"100.728032ms","start":"2026-05-14T13:16:03.480149Z","end":"2026-05-14T13:16:03.580877Z","steps":["trace[2132490089] 'agreement among raft nodes before linearized reading' (duration: 100.696151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.580937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.742524ms","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:5"} {"level":"info","ts":"2026-05-14T13:16:03.58096Z","caller":"traceutil/trace.go:171","msg":"trace[99447676] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"170.776695ms","start":"2026-05-14T13:16:03.410178Z","end":"2026-05-14T13:16:03.580954Z","steps":["trace[99447676] 'agreement among raft nodes before linearized reading' (duration: 170.742954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.580963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.198186ms","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:5"} {"level":"info","ts":"2026-05-14T13:16:03.581005Z","caller":"traceutil/trace.go:171","msg":"trace[772318572] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"193.282518ms","start":"2026-05-14T13:16:03.387713Z","end":"2026-05-14T13:16:03.580995Z","steps":["trace[772318572] 'agreement among raft nodes before linearized reading' (duration: 193.183795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.580799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.267942ms","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:5"} {"level":"warn","ts":"2026-05-14T13:16:03.581059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.182405ms","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:5"} {"level":"info","ts":"2026-05-14T13:16:03.581087Z","caller":"traceutil/trace.go:171","msg":"trace[1034832863] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"288.224177ms","start":"2026-05-14T13:16:03.292856Z","end":"2026-05-14T13:16:03.58108Z","steps":["trace[1034832863] 'agreement among raft nodes before linearized reading' (duration: 288.183995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.581166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.282936ms","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:5"} {"level":"warn","ts":"2026-05-14T13:16:03.581193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.602596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:16:03.581218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.691228ms","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:5"} {"level":"info","ts":"2026-05-14T13:16:03.581229Z","caller":"traceutil/trace.go:171","msg":"trace[1325266385] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"311.656207ms","start":"2026-05-14T13:16:03.269564Z","end":"2026-05-14T13:16:03.58122Z","steps":["trace[1325266385] 'agreement among raft nodes before linearized reading' (duration: 311.599895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.581253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.61057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:16:03.581272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:03.269556Z","time spent":"311.707598ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 "} {"level":"info","ts":"2026-05-14T13:16:03.581245Z","caller":"traceutil/trace.go:171","msg":"trace[1053618510] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"218.730189ms","start":"2026-05-14T13:16:03.362507Z","end":"2026-05-14T13:16:03.581237Z","steps":["trace[1053618510] 'agreement among raft nodes before linearized reading' (duration: 218.699248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.58132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.909265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:03.581344Z","caller":"traceutil/trace.go:171","msg":"trace[511746005] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"218.956175ms","start":"2026-05-14T13:16:03.362383Z","end":"2026-05-14T13:16:03.581339Z","steps":["trace[511746005] 'agreement among raft nodes before linearized reading' (duration: 218.922014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.581392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.398682ms","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-14T13:16:03.581432Z","caller":"traceutil/trace.go:171","msg":"trace[1367120794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1757; }","duration":"289.445524ms","start":"2026-05-14T13:16:03.291975Z","end":"2026-05-14T13:16:03.58142Z","steps":["trace[1367120794] 'agreement among raft nodes before linearized reading' (duration: 289.332399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.581193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.715931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:03.581515Z","caller":"traceutil/trace.go:171","msg":"trace[1386599698] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"194.064552ms","start":"2026-05-14T13:16:03.387439Z","end":"2026-05-14T13:16:03.581504Z","steps":["trace[1386599698] 'agreement among raft nodes before linearized reading' (duration: 193.731291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.580832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.637169ms","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:5"} {"level":"info","ts":"2026-05-14T13:16:03.581556Z","caller":"traceutil/trace.go:171","msg":"trace[2112750560] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"101.377241ms","start":"2026-05-14T13:16:03.480172Z","end":"2026-05-14T13:16:03.581549Z","steps":["trace[2112750560] 'agreement among raft nodes before linearized reading' (duration: 100.644439ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:03.58128Z","caller":"traceutil/trace.go:171","msg":"trace[1163152169] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"262.665771ms","start":"2026-05-14T13:16:03.318609Z","end":"2026-05-14T13:16:03.581275Z","steps":["trace[1163152169] 'agreement among raft nodes before linearized reading' (duration: 262.62466ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:03.581228Z","caller":"traceutil/trace.go:171","msg":"trace[893996243] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"311.382509ms","start":"2026-05-14T13:16:03.269833Z","end":"2026-05-14T13:16:03.581215Z","steps":["trace[893996243] 'agreement among raft nodes before linearized reading' (duration: 311.281876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.581403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.01536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T13:16:03.581656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:03.269817Z","time spent":"311.830123ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-14T13:16:03.581666Z","caller":"traceutil/trace.go:171","msg":"trace[2102616642] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1757; }","duration":"282.286819ms","start":"2026-05-14T13:16:03.29937Z","end":"2026-05-14T13:16:03.581656Z","steps":["trace[2102616642] 'agreement among raft nodes before linearized reading' (duration: 282.0152ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:03.581063Z","caller":"traceutil/trace.go:171","msg":"trace[498594649] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"151.548771ms","start":"2026-05-14T13:16:03.429507Z","end":"2026-05-14T13:16:03.581056Z","steps":["trace[498594649] 'agreement among raft nodes before linearized reading' (duration: 151.259352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.581278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.545096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:03.5818Z","caller":"traceutil/trace.go:171","msg":"trace[1214802282] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"289.055942ms","start":"2026-05-14T13:16:03.292709Z","end":"2026-05-14T13:16:03.581765Z","steps":["trace[1214802282] 'agreement among raft nodes before linearized reading' (duration: 288.543996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:03.581439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.756435ms","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:5"} {"level":"info","ts":"2026-05-14T13:16:03.581935Z","caller":"traceutil/trace.go:171","msg":"trace[1794072420] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1757; }","duration":"263.27454ms","start":"2026-05-14T13:16:03.318647Z","end":"2026-05-14T13:16:03.581921Z","steps":["trace[1794072420] 'agreement among raft nodes before linearized reading' (duration: 262.776906ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:11.358177Z","caller":"traceutil/trace.go:171","msg":"trace[1278321590] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"434.256124ms","start":"2026-05-14T13:16:10.923902Z","end":"2026-05-14T13:16:11.358158Z","steps":["trace[1278321590] 'read index received' (duration: 434.136649ms)","trace[1278321590] 'applied index is now lower than readState.Index' (duration: 118.604µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:16:11.358258Z","caller":"traceutil/trace.go:171","msg":"trace[2090680468] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"741.512953ms","start":"2026-05-14T13:16:10.616738Z","end":"2026-05-14T13:16:11.35825Z","steps":["trace[2090680468] 'process raft request' (duration: 741.290966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:11.358473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:10.616713Z","time spent":"741.655397ms","remote":"127.0.0.1:46046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:16:11.35856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.645685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66f4cfd5-84da-4f74-aa7e-36ef8e347f34\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-05-14T13:16:11.358724Z","caller":"traceutil/trace.go:171","msg":"trace[1459140210] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-66f4cfd5-84da-4f74-aa7e-36ef8e347f34; range_end:; response_count:1; response_revision:1861; }","duration":"434.856641ms","start":"2026-05-14T13:16:10.923845Z","end":"2026-05-14T13:16:11.358701Z","steps":["trace[1459140210] 'agreement among raft nodes before linearized reading' (duration: 434.646906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:11.35881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:10.923825Z","time spent":"434.938314ms","remote":"127.0.0.1:46046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66f4cfd5-84da-4f74-aa7e-36ef8e347f34\" "} {"level":"info","ts":"2026-05-14T13:16:11.410721Z","caller":"traceutil/trace.go:171","msg":"trace[1975679441] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"228.423312ms","start":"2026-05-14T13:16:11.182267Z","end":"2026-05-14T13:16:11.410691Z","steps":["trace[1975679441] 'process raft request' (duration: 228.287438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:11.410776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.451155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:11.41082Z","caller":"traceutil/trace.go:171","msg":"trace[882496451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1862; }","duration":"394.519638ms","start":"2026-05-14T13:16:11.016287Z","end":"2026-05-14T13:16:11.410807Z","steps":["trace[882496451] 'agreement among raft nodes before linearized reading' (duration: 394.446636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:11.410853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:11.016274Z","time spent":"394.573369ms","remote":"127.0.0.1:45886","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-14T13:16:11.410813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.181226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-14T13:16:11.41098Z","caller":"traceutil/trace.go:171","msg":"trace[1433667126] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1862; }","duration":"476.34877ms","start":"2026-05-14T13:16:10.934611Z","end":"2026-05-14T13:16:11.41096Z","steps":["trace[1433667126] 'agreement among raft nodes before linearized reading' (duration: 476.041402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:11.411033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:10.934568Z","time spent":"476.451434ms","remote":"127.0.0.1:42206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T13:16:11.411177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.629045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:11.411251Z","caller":"traceutil/trace.go:171","msg":"trace[2119565621] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1862; }","duration":"225.733338ms","start":"2026-05-14T13:16:11.185504Z","end":"2026-05-14T13:16:11.411238Z","steps":["trace[2119565621] 'agreement among raft nodes before linearized reading' (duration: 225.638215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:12.126688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869392629441426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T13:16:12.233531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.548778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:16:12.233724Z","caller":"traceutil/trace.go:171","msg":"trace[94910778] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"786.297116ms","start":"2026-05-14T13:16:11.447339Z","end":"2026-05-14T13:16:12.233636Z","steps":["trace[94910778] 'process raft request' (duration: 34.540822ms)","trace[94910778] 'compare' (duration: 751.415735ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:16:12.23385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:11.447318Z","time spent":"786.489701ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:16:12.284545Z","caller":"traceutil/trace.go:171","msg":"trace[1762832787] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"811.969549ms","start":"2026-05-14T13:16:11.472346Z","end":"2026-05-14T13:16:12.284315Z","steps":["trace[1762832787] 'process raft request' (duration: 811.806785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:12.284718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:11.472323Z","time spent":"812.282648ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:16:12.320852Z","caller":"traceutil/trace.go:171","msg":"trace[785408063] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"708.349835ms","start":"2026-05-14T13:16:11.612492Z","end":"2026-05-14T13:16:12.320842Z","steps":["trace[785408063] 'process raft request' (duration: 708.278823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:12.320917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:11.612466Z","time spent":"708.419526ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:16:12.321001Z","caller":"traceutil/trace.go:171","msg":"trace[1742694130] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1933; }","duration":"695.286695ms","start":"2026-05-14T13:16:11.625698Z","end":"2026-05-14T13:16:12.320985Z","steps":["trace[1742694130] 'read index received' (duration: 658.540943ms)","trace[1742694130] 'applied index is now lower than readState.Index' (duration: 36.744852ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:16:12.321152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.453729ms","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-14T13:16:12.321181Z","caller":"traceutil/trace.go:171","msg":"trace[1367343052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1866; }","duration":"695.48277ms","start":"2026-05-14T13:16:11.625692Z","end":"2026-05-14T13:16:12.321175Z","steps":["trace[1367343052] 'agreement among raft nodes before linearized reading' (duration: 695.383578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:12.321209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.235205ms","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":"warn","ts":"2026-05-14T13:16:12.3212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:11.625671Z","time spent":"695.524211ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T13:16:12.321265Z","caller":"traceutil/trace.go:171","msg":"trace[411934719] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:1866; }","duration":"576.326477ms","start":"2026-05-14T13:16:11.744924Z","end":"2026-05-14T13:16:12.32125Z","steps":["trace[411934719] 'agreement among raft nodes before linearized reading' (duration: 576.238245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:12.321318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:11.744911Z","time spent":"576.392059ms","remote":"127.0.0.1:45906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"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-14T13:16:12.321572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.450648ms","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-14T13:16:12.321603Z","caller":"traceutil/trace.go:171","msg":"trace[1609630673] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1866; }","duration":"508.52289ms","start":"2026-05-14T13:16:11.813073Z","end":"2026-05-14T13:16:12.321596Z","steps":["trace[1609630673] 'agreement among raft nodes before linearized reading' (duration: 508.449398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:12.321599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.767608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-14T13:16:12.321626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:11.813052Z","time spent":"508.567901ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-14T13:16:12.321646Z","caller":"traceutil/trace.go:171","msg":"trace[1056368708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1866; }","duration":"320.851211ms","start":"2026-05-14T13:16:12.000781Z","end":"2026-05-14T13:16:12.321632Z","steps":["trace[1056368708] 'agreement among raft nodes before linearized reading' (duration: 320.724087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:12.321686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:12.000761Z","time spent":"320.914312ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T13:16:19.922205Z","caller":"traceutil/trace.go:171","msg":"trace[667986134] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"118.753426ms","start":"2026-05-14T13:16:19.803426Z","end":"2026-05-14T13:16:19.922179Z","steps":["trace[667986134] 'process raft request' (duration: 118.582392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:26.897501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.394199963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:26.897604Z","caller":"traceutil/trace.go:171","msg":"trace[1122891159] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1980; }","duration":"1.394361077s","start":"2026-05-14T13:16:25.503218Z","end":"2026-05-14T13:16:26.897579Z","steps":["trace[1122891159] 'count revisions from in-memory index tree' (duration: 1.39409851s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:26.897591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.387749337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-14T13:16:26.897652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:25.503198Z","time spent":"1.394442358s","remote":"127.0.0.1:52154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-05-14T13:16:26.897673Z","caller":"traceutil/trace.go:171","msg":"trace[1204273486] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1980; }","duration":"1.38788657s","start":"2026-05-14T13:16:25.509764Z","end":"2026-05-14T13:16:26.89765Z","steps":["trace[1204273486] 'range keys from in-memory index tree' (duration: 1.387596023s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:26.897717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:25.509756Z","time spent":"1.387951072s","remote":"127.0.0.1:42206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T13:16:26.897709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.04485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:26.897818Z","caller":"traceutil/trace.go:171","msg":"trace[1325453790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1980; }","duration":"882.179374ms","start":"2026-05-14T13:16:26.015587Z","end":"2026-05-14T13:16:26.897766Z","steps":["trace[1325453790] 'range keys from in-memory index tree' (duration: 881.963589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:26.897859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:26.015566Z","time spent":"882.284446ms","remote":"127.0.0.1:45886","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-14T13:16:26.898194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.537162ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:16:26.898257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140479101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-h9rr4\" ","response":"range_response_count:1 size:5852"} {"level":"info","ts":"2026-05-14T13:16:26.898336Z","caller":"traceutil/trace.go:171","msg":"trace[666442970] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-h9rr4; range_end:; response_count:1; response_revision:1980; }","duration":"1.140603604s","start":"2026-05-14T13:16:25.757712Z","end":"2026-05-14T13:16:26.898315Z","steps":["trace[666442970] 'range keys from in-memory index tree' (duration: 1.140265586s)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:26.898352Z","caller":"traceutil/trace.go:171","msg":"trace[1993946408] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1980; }","duration":"988.701435ms","start":"2026-05-14T13:16:25.909628Z","end":"2026-05-14T13:16:26.89833Z","steps":["trace[1993946408] 'range keys from in-memory index tree' (duration: 988.519871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:26.898376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:25.757692Z","time spent":"1.140675035s","remote":"127.0.0.1:46046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":5874,"request content":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-h9rr4\" "} {"level":"warn","ts":"2026-05-14T13:16:26.898497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.247025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:16:26.898566Z","caller":"traceutil/trace.go:171","msg":"trace[1540400942] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"610.597043ms","start":"2026-05-14T13:16:26.287955Z","end":"2026-05-14T13:16:26.898552Z","steps":["trace[1540400942] 'process raft request' (duration: 393.204194ms)","trace[1540400942] 'compare' (duration: 217.073711ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:16:26.898615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:26.287928Z","time spent":"610.661093ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:16:26.898777Z","caller":"traceutil/trace.go:171","msg":"trace[1149051131] linearizableReadLoop","detail":"{readStateIndex:2057; appliedIndex:2054; }","duration":"424.264255ms","start":"2026-05-14T13:16:26.4745Z","end":"2026-05-14T13:16:26.898764Z","steps":["trace[1149051131] 'read index received' (duration: 206.762313ms)","trace[1149051131] 'applied index is now lower than readState.Index' (duration: 217.501002ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:16:26.898895Z","caller":"traceutil/trace.go:171","msg":"trace[144900420] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"519.811525ms","start":"2026-05-14T13:16:26.37907Z","end":"2026-05-14T13:16:26.898881Z","steps":["trace[144900420] 'process raft request' (duration: 519.533969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:26.898981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.48131ms","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-14T13:16:26.899015Z","caller":"traceutil/trace.go:171","msg":"trace[749487227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1983; }","duration":"424.551232ms","start":"2026-05-14T13:16:26.474452Z","end":"2026-05-14T13:16:26.899003Z","steps":["trace[749487227] 'agreement among raft nodes before linearized reading' (duration: 424.400779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:26.899046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:26.474436Z","time spent":"424.602153ms","remote":"127.0.0.1:46108","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-14T13:16:26.899048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:26.379044Z","time spent":"519.886768ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:16:26.899174Z","caller":"traceutil/trace.go:171","msg":"trace[721235365] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"516.930327ms","start":"2026-05-14T13:16:26.38223Z","end":"2026-05-14T13:16:26.89916Z","steps":["trace[721235365] 'process raft request' (duration: 516.491557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:26.899239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:26.38221Z","time spent":"516.997848ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:16:26.900055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.696449ms","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-14T13:16:26.900104Z","caller":"traceutil/trace.go:171","msg":"trace[12953722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1983; }","duration":"394.767731ms","start":"2026-05-14T13:16:26.505323Z","end":"2026-05-14T13:16:26.90009Z","steps":["trace[12953722] 'agreement among raft nodes before linearized reading' (duration: 394.649698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:26.900134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:26.505311Z","time spent":"394.816363ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T13:16:26.900733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.51611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-14T13:16:26.900757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.970865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:16:26.900773Z","caller":"traceutil/trace.go:171","msg":"trace[953650267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1983; }","duration":"390.578862ms","start":"2026-05-14T13:16:26.510183Z","end":"2026-05-14T13:16:26.900762Z","steps":["trace[953650267] 'agreement among raft nodes before linearized reading' (duration: 390.399588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:26.90077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.612269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-14T13:16:26.900803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:26.510169Z","time spent":"390.628083ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T13:16:26.900803Z","caller":"traceutil/trace.go:171","msg":"trace[2065201965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1983; }","duration":"392.133168ms","start":"2026-05-14T13:16:26.508656Z","end":"2026-05-14T13:16:26.90079Z","steps":["trace[2065201965] 'agreement among raft nodes before linearized reading' (duration: 391.968074ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:26.900815Z","caller":"traceutil/trace.go:171","msg":"trace[1503989702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1983; }","duration":"392.65933ms","start":"2026-05-14T13:16:26.508143Z","end":"2026-05-14T13:16:26.900802Z","steps":["trace[1503989702] 'agreement among raft nodes before linearized reading' (duration: 392.520467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:26.900838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:26.508646Z","time spent":"392.18582ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:16:26.900852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:26.508106Z","time spent":"392.734372ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T13:16:42.517058Z","caller":"traceutil/trace.go:171","msg":"trace[2009851656] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"133.827923ms","start":"2026-05-14T13:16:42.383206Z","end":"2026-05-14T13:16:42.517034Z","steps":["trace[2009851656] 'process raft request' (duration: 133.419874ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:42.564189Z","caller":"traceutil/trace.go:171","msg":"trace[336406063] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"124.906198ms","start":"2026-05-14T13:16:42.439267Z","end":"2026-05-14T13:16:42.564174Z","steps":["trace[336406063] 'process raft request' (duration: 124.803135ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:43.3827Z","caller":"traceutil/trace.go:171","msg":"trace[337827604] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2164; }","duration":"407.719622ms","start":"2026-05-14T13:16:42.974957Z","end":"2026-05-14T13:16:43.382676Z","steps":["trace[337827604] 'read index received' (duration: 31.168907ms)","trace[337827604] 'applied index is now lower than readState.Index' (duration: 376.549815ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:16:43.382771Z","caller":"traceutil/trace.go:171","msg":"trace[43369820] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"428.770461ms","start":"2026-05-14T13:16:42.953958Z","end":"2026-05-14T13:16:43.382728Z","steps":["trace[43369820] 'process raft request' (duration: 418.660063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.382896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:42.953931Z","time spent":"428.910423ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:16:43.382967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.939135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:16:43.38301Z","caller":"traceutil/trace.go:171","msg":"trace[2031316191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2090; }","duration":"406.016937ms","start":"2026-05-14T13:16:42.976982Z","end":"2026-05-14T13:16:43.382999Z","steps":["trace[2031316191] 'agreement among raft nodes before linearized reading' (duration: 405.834343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.383033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.10788ms","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-14T13:16:43.383055Z","caller":"traceutil/trace.go:171","msg":"trace[1620521820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2090; }","duration":"408.159381ms","start":"2026-05-14T13:16:42.97489Z","end":"2026-05-14T13:16:43.38305Z","steps":["trace[1620521820] 'agreement among raft nodes before linearized reading' (duration: 408.072439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.383076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:42.974878Z","time spent":"408.195302ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T13:16:43.383049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:42.976972Z","time spent":"406.069148ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:16:43.383119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.233209ms","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-14T13:16:43.383158Z","caller":"traceutil/trace.go:171","msg":"trace[37946398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2090; }","duration":"378.28411ms","start":"2026-05-14T13:16:43.004865Z","end":"2026-05-14T13:16:43.38315Z","steps":["trace[37946398] 'agreement among raft nodes before linearized reading' (duration: 378.197508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.383186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:43.004859Z","time spent":"378.321822ms","remote":"127.0.0.1:46108","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-14T13:16:43.3832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.51536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-14T13:16:43.383234Z","caller":"traceutil/trace.go:171","msg":"trace[1609662556] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2090; }","duration":"385.576482ms","start":"2026-05-14T13:16:42.997649Z","end":"2026-05-14T13:16:43.383225Z","steps":["trace[1609662556] 'agreement among raft nodes before linearized reading' (duration: 385.49695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.383269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:42.997639Z","time spent":"385.623902ms","remote":"127.0.0.1:42206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T13:16:43.383275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.961018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:43.3833Z","caller":"traceutil/trace.go:171","msg":"trace[1228131169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2090; }","duration":"368.017898ms","start":"2026-05-14T13:16:43.015276Z","end":"2026-05-14T13:16:43.383294Z","steps":["trace[1228131169] 'agreement among raft nodes before linearized reading' (duration: 367.981207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.383319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:43.015265Z","time spent":"368.05024ms","remote":"127.0.0.1:45886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T13:16:43.383297Z","caller":"traceutil/trace.go:171","msg":"trace[2058460143] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"428.542246ms","start":"2026-05-14T13:16:42.954728Z","end":"2026-05-14T13:16:43.38327Z","steps":["trace[2058460143] 'process raft request' (duration: 427.851332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.383302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.847786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-14T13:16:43.383125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.591132ms","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-14T13:16:43.383373Z","caller":"traceutil/trace.go:171","msg":"trace[92117095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2090; }","duration":"384.875396ms","start":"2026-05-14T13:16:42.998487Z","end":"2026-05-14T13:16:43.383363Z","steps":["trace[92117095] 'agreement among raft nodes before linearized reading' (duration: 384.581971ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:43.383367Z","caller":"traceutil/trace.go:171","msg":"trace[307647652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2090; }","duration":"384.950948ms","start":"2026-05-14T13:16:42.998397Z","end":"2026-05-14T13:16:43.383348Z","steps":["trace[307647652] 'agreement among raft nodes before linearized reading' (duration: 384.736684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.383409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:42.998466Z","time spent":"384.935329ms","remote":"127.0.0.1:46108","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-14T13:16:43.38341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:42.998385Z","time spent":"385.017551ms","remote":"127.0.0.1:46108","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-14T13:16:43.383414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:42.954714Z","time spent":"428.635727ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:16:43.383311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.312337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-14T13:16:43.383496Z","caller":"traceutil/trace.go:171","msg":"trace[711677978] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:2090; }","duration":"259.542693ms","start":"2026-05-14T13:16:43.123946Z","end":"2026-05-14T13:16:43.383489Z","steps":["trace[711677978] 'agreement among raft nodes before linearized reading' (duration: 259.287887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.889994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.421999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:16:43.89023Z","caller":"traceutil/trace.go:171","msg":"trace[1805938670] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"502.982951ms","start":"2026-05-14T13:16:43.387219Z","end":"2026-05-14T13:16:43.890202Z","steps":["trace[1805938670] 'process raft request' (duration: 196.257876ms)","trace[1805938670] 'compare' (duration: 306.130153ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:16:43.890302Z","caller":"traceutil/trace.go:171","msg":"trace[450054146] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"502.440791ms","start":"2026-05-14T13:16:43.387843Z","end":"2026-05-14T13:16:43.890284Z","steps":["trace[450054146] 'process raft request' (duration: 502.38395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.890379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:43.387834Z","time spent":"502.511892ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:16:43.890381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:43.387201Z","time spent":"503.103094ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:16:43.890679Z","caller":"traceutil/trace.go:171","msg":"trace[455957194] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"503.453842ms","start":"2026-05-14T13:16:43.387213Z","end":"2026-05-14T13:16:43.890667Z","steps":["trace[455957194] 'process raft request' (duration: 502.870439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.890746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:43.387192Z","time spent":"503.524234ms","remote":"127.0.0.1:46108","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-14T13:16:43.890854Z","caller":"traceutil/trace.go:171","msg":"trace[2027559961] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"503.489913ms","start":"2026-05-14T13:16:43.387356Z","end":"2026-05-14T13:16:43.890846Z","steps":["trace[2027559961] 'process raft request' (duration: 502.812379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.890907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:43.387349Z","time spent":"503.536264ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:16:43.891062Z","caller":"traceutil/trace.go:171","msg":"trace[1475192207] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"503.451852ms","start":"2026-05-14T13:16:43.387598Z","end":"2026-05-14T13:16:43.89105Z","steps":["trace[1475192207] 'process raft request' (duration: 502.601384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.891145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:43.387589Z","time spent":"503.512763ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:16:43.929767Z","caller":"traceutil/trace.go:171","msg":"trace[2087078240] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"397.16681ms","start":"2026-05-14T13:16:43.532583Z","end":"2026-05-14T13:16:43.92975Z","steps":["trace[2087078240] 'process raft request' (duration: 397.121569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.929909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:43.532564Z","time spent":"397.291042ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:16:43.929903Z","caller":"traceutil/trace.go:171","msg":"trace[1165865705] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"503.119235ms","start":"2026-05-14T13:16:43.426757Z","end":"2026-05-14T13:16:43.929876Z","steps":["trace[1165865705] 'process raft request' (duration: 502.753376ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:43.929901Z","caller":"traceutil/trace.go:171","msg":"trace[2016691106] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"445.581597ms","start":"2026-05-14T13:16:43.484279Z","end":"2026-05-14T13:16:43.929861Z","steps":["trace[2016691106] 'process raft request' (duration: 445.371712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:43.930019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:43.426737Z","time spent":"503.219997ms","remote":"127.0.0.1:45904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:16:43.930156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:43.484259Z","time spent":"445.737091ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:16:44.456365Z","caller":"traceutil/trace.go:171","msg":"trace[1468224339] linearizableReadLoop","detail":"{readStateIndex:2177; appliedIndex:2176; }","duration":"147.046588ms","start":"2026-05-14T13:16:44.309299Z","end":"2026-05-14T13:16:44.456346Z","steps":["trace[1468224339] 'read index received' (duration: 146.860833ms)","trace[1468224339] 'applied index is now lower than readState.Index' (duration: 184.685µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:16:44.456418Z","caller":"traceutil/trace.go:171","msg":"trace[1548295920] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"158.959642ms","start":"2026-05-14T13:16:44.297431Z","end":"2026-05-14T13:16:44.456391Z","steps":["trace[1548295920] 'process raft request' (duration: 158.791217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:44.456655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.352965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12380"} {"level":"info","ts":"2026-05-14T13:16:44.456728Z","caller":"traceutil/trace.go:171","msg":"trace[1961802254] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2099; }","duration":"147.483177ms","start":"2026-05-14T13:16:44.309228Z","end":"2026-05-14T13:16:44.456711Z","steps":["trace[1961802254] 'agreement among raft nodes before linearized reading' (duration: 147.235622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:45.301327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.137804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:16:45.301734Z","caller":"traceutil/trace.go:171","msg":"trace[866222773] linearizableReadLoop","detail":"{readStateIndex:2179; appliedIndex:2178; }","duration":"286.354002ms","start":"2026-05-14T13:16:45.015278Z","end":"2026-05-14T13:16:45.301632Z","steps":["trace[866222773] 'read index received' (duration: 147.759198ms)","trace[866222773] 'applied index is now lower than readState.Index' (duration: 138.592584ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:16:45.302035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.74755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:45.302151Z","caller":"traceutil/trace.go:171","msg":"trace[582505342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2101; }","duration":"286.890062ms","start":"2026-05-14T13:16:45.015249Z","end":"2026-05-14T13:16:45.302139Z","steps":["trace[582505342] 'agreement among raft nodes before linearized reading' (duration: 286.620847ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:45.302059Z","caller":"traceutil/trace.go:171","msg":"trace[112480465] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"286.828881ms","start":"2026-05-14T13:16:45.015206Z","end":"2026-05-14T13:16:45.302035Z","steps":["trace[112480465] 'process raft request' (duration: 147.914111ms)","trace[112480465] 'compare' (duration: 138.029262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:16:45.746652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.484712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:16:45.74685Z","caller":"traceutil/trace.go:171","msg":"trace[564076498] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"359.419903ms","start":"2026-05-14T13:16:45.387409Z","end":"2026-05-14T13:16:45.746829Z","steps":["trace[564076498] 'process raft request' (duration: 112.524853ms)","trace[564076498] 'compare' (duration: 246.260057ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:16:45.746924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:45.387394Z","time spent":"359.495424ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:16:45.747438Z","caller":"traceutil/trace.go:171","msg":"trace[422862058] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2179; }","duration":"283.947122ms","start":"2026-05-14T13:16:45.463474Z","end":"2026-05-14T13:16:45.747421Z","steps":["trace[422862058] 'read index received' (duration: 36.523171ms)","trace[422862058] 'applied index is now lower than readState.Index' (duration: 247.422951ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:16:45.74758Z","caller":"traceutil/trace.go:171","msg":"trace[1337998805] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"359.80429ms","start":"2026-05-14T13:16:45.387704Z","end":"2026-05-14T13:16:45.747508Z","steps":["trace[1337998805] 'process raft request' (duration: 359.043585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:45.747725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.249487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-14T13:16:45.747763Z","caller":"traceutil/trace.go:171","msg":"trace[1557607530] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2103; }","duration":"284.315809ms","start":"2026-05-14T13:16:45.463437Z","end":"2026-05-14T13:16:45.747753Z","steps":["trace[1557607530] 'agreement among raft nodes before linearized reading' (duration: 284.041973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:45.747883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:45.387682Z","time spent":"360.018065ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:16:46.077301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.263427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-14T13:16:46.07737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.99456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T13:16:46.07744Z","caller":"traceutil/trace.go:171","msg":"trace[763361066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2103; }","duration":"181.090783ms","start":"2026-05-14T13:16:45.896327Z","end":"2026-05-14T13:16:46.077417Z","steps":["trace[763361066] 'range keys from in-memory index tree' (duration: 180.859378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:46.077662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.038301ms","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-14T13:16:46.077688Z","caller":"traceutil/trace.go:171","msg":"trace[1842580835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2103; }","duration":"181.098783ms","start":"2026-05-14T13:16:45.896582Z","end":"2026-05-14T13:16:46.07768Z","steps":["trace[1842580835] 'range keys from in-memory index tree' (duration: 180.881259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:46.077881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.450007ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:16:46.077941Z","caller":"traceutil/trace.go:171","msg":"trace[1612068930] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2103; }","duration":"168.513718ms","start":"2026-05-14T13:16:45.909419Z","end":"2026-05-14T13:16:46.077933Z","steps":["trace[1612068930] 'range keys from in-memory index tree' (duration: 168.433796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:46.077977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.085984ms","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-14T13:16:46.078304Z","caller":"traceutil/trace.go:171","msg":"trace[1611696702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2103; }","duration":"183.157765ms","start":"2026-05-14T13:16:45.894852Z","end":"2026-05-14T13:16:46.07801Z","steps":["trace[1611696702] 'range keys from in-memory index tree' (duration: 182.95597ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:46.077392Z","caller":"traceutil/trace.go:171","msg":"trace[709969053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2103; }","duration":"181.39127ms","start":"2026-05-14T13:16:45.895966Z","end":"2026-05-14T13:16:46.077358Z","steps":["trace[709969053] 'range keys from in-memory index tree' (duration: 181.094614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:46.078526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.671695ms","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-14T13:16:46.078595Z","caller":"traceutil/trace.go:171","msg":"trace[97901322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2103; }","duration":"183.766397ms","start":"2026-05-14T13:16:45.894795Z","end":"2026-05-14T13:16:46.078562Z","steps":["trace[97901322] 'range keys from in-memory index tree' (duration: 183.478522ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:46.078841Z","caller":"traceutil/trace.go:171","msg":"trace[2121366047] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"144.171925ms","start":"2026-05-14T13:16:45.934656Z","end":"2026-05-14T13:16:46.078828Z","steps":["trace[2121366047] 'process raft request' (duration: 86.234828ms)","trace[2121366047] 'compare' (duration: 56.301103ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:16:46.280144Z","caller":"traceutil/trace.go:171","msg":"trace[1215277839] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"195.20933ms","start":"2026-05-14T13:16:46.084904Z","end":"2026-05-14T13:16:46.280113Z","steps":["trace[1215277839] 'process raft request' (duration: 130.776233ms)","trace[1215277839] 'compare' (duration: 63.752442ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:16:46.280339Z","caller":"traceutil/trace.go:171","msg":"trace[1117145433] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"195.108808ms","start":"2026-05-14T13:16:46.085207Z","end":"2026-05-14T13:16:46.280316Z","steps":["trace[1117145433] 'process raft request' (duration: 195.057227ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:46.280585Z","caller":"traceutil/trace.go:171","msg":"trace[355965770] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"195.611138ms","start":"2026-05-14T13:16:46.084953Z","end":"2026-05-14T13:16:46.280565Z","steps":["trace[355965770] 'process raft request' (duration: 195.21905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:46.841065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.293099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T13:16:46.841155Z","caller":"traceutil/trace.go:171","msg":"trace[1066754462] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2109; }","duration":"175.399781ms","start":"2026-05-14T13:16:46.665738Z","end":"2026-05-14T13:16:46.841138Z","steps":["trace[1066754462] 'count revisions from in-memory index tree' (duration: 175.116716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:46.84115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.991886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T13:16:46.841268Z","caller":"traceutil/trace.go:171","msg":"trace[542554664] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2109; }","duration":"424.15965ms","start":"2026-05-14T13:16:46.417088Z","end":"2026-05-14T13:16:46.841247Z","steps":["trace[542554664] 'count revisions from in-memory index tree' (duration: 423.916525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:46.841313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:16:46.417066Z","time spent":"424.234311ms","remote":"127.0.0.1:52102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-05-14T13:16:47.552824Z","caller":"traceutil/trace.go:171","msg":"trace[1053555849] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"242.178528ms","start":"2026-05-14T13:16:47.310322Z","end":"2026-05-14T13:16:47.552501Z","steps":["trace[1053555849] 'process raft request' (duration: 241.979185ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:47.935609Z","caller":"traceutil/trace.go:171","msg":"trace[219221727] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"182.407033ms","start":"2026-05-14T13:16:47.753182Z","end":"2026-05-14T13:16:47.935589Z","steps":["trace[219221727] 'process raft request' (duration: 182.292661ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:16:47.96801Z","caller":"traceutil/trace.go:171","msg":"trace[1515065913] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2189; }","duration":"107.753553ms","start":"2026-05-14T13:16:47.860219Z","end":"2026-05-14T13:16:47.967972Z","steps":["trace[1515065913] 'read index received' (duration: 75.371155ms)","trace[1515065913] 'applied index is now lower than readState.Index' (duration: 32.380698ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:16:47.968182Z","caller":"traceutil/trace.go:171","msg":"trace[26713946] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"214.696529ms","start":"2026-05-14T13:16:47.753403Z","end":"2026-05-14T13:16:47.9681Z","steps":["trace[26713946] 'process raft request' (duration: 214.362823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:16:47.968367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.11403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-14T13:16:47.968422Z","caller":"traceutil/trace.go:171","msg":"trace[799511409] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2112; }","duration":"108.229292ms","start":"2026-05-14T13:16:47.860174Z","end":"2026-05-14T13:16:47.968404Z","steps":["trace[799511409] 'agreement among raft nodes before linearized reading' (duration: 107.932136ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:17:48.640154Z","caller":"traceutil/trace.go:171","msg":"trace[1092748034] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"175.276917ms","start":"2026-05-14T13:17:48.464854Z","end":"2026-05-14T13:17:48.64013Z","steps":["trace[1092748034] 'process raft request' (duration: 175.111015ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:17:48.641104Z","caller":"traceutil/trace.go:171","msg":"trace[184300700] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"176.108389ms","start":"2026-05-14T13:17:48.464949Z","end":"2026-05-14T13:17:48.641058Z","steps":["trace[184300700] 'process raft request' (duration: 175.997276ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:17:56.923645Z","caller":"traceutil/trace.go:171","msg":"trace[939699299] transaction","detail":"{read_only:false; response_revision:2583; number_of_response:1; }","duration":"242.808853ms","start":"2026-05-14T13:17:56.680817Z","end":"2026-05-14T13:17:56.923626Z","steps":["trace[939699299] 'process raft request' (duration: 242.693922ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:17:56.924848Z","caller":"traceutil/trace.go:171","msg":"trace[1486967537] transaction","detail":"{read_only:false; response_revision:2584; number_of_response:1; }","duration":"243.122148ms","start":"2026-05-14T13:17:56.681701Z","end":"2026-05-14T13:17:56.924823Z","steps":["trace[1486967537] 'process raft request' (duration: 243.006676ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:17:56.924973Z","caller":"traceutil/trace.go:171","msg":"trace[1118318329] linearizableReadLoop","detail":"{readStateIndex:2679; appliedIndex:2677; }","duration":"233.453429ms","start":"2026-05-14T13:17:56.69151Z","end":"2026-05-14T13:17:56.924964Z","steps":["trace[1118318329] 'read index received' (duration: 232.584157ms)","trace[1118318329] 'applied index is now lower than readState.Index' (duration: 868.612µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:17:56.925045Z","caller":"traceutil/trace.go:171","msg":"trace[1195776552] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"242.973326ms","start":"2026-05-14T13:17:56.682046Z","end":"2026-05-14T13:17:56.925019Z","steps":["trace[1195776552] 'process raft request' (duration: 242.734692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:17:56.925285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.762143ms","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-14T13:17:56.925328Z","caller":"traceutil/trace.go:171","msg":"trace[847971131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2585; }","duration":"233.834313ms","start":"2026-05-14T13:17:56.691484Z","end":"2026-05-14T13:17:56.925319Z","steps":["trace[847971131] 'agreement among raft nodes before linearized reading' (duration: 233.54426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:17:56.925368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.375148ms","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-14T13:17:56.925451Z","caller":"traceutil/trace.go:171","msg":"trace[1399840180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2585; }","duration":"233.480899ms","start":"2026-05-14T13:17:56.691951Z","end":"2026-05-14T13:17:56.925432Z","steps":["trace[1399840180] 'agreement among raft nodes before linearized reading' (duration: 233.290286ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:17:57.71854Z","caller":"traceutil/trace.go:171","msg":"trace[2024979934] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"149.861701ms","start":"2026-05-14T13:17:57.568657Z","end":"2026-05-14T13:17:57.718519Z","steps":["trace[2024979934] 'process raft request' (duration: 149.74349ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:17:57.719212Z","caller":"traceutil/trace.go:171","msg":"trace[1049379713] linearizableReadLoop","detail":"{readStateIndex:2682; appliedIndex:2682; }","duration":"119.701408ms","start":"2026-05-14T13:17:57.599487Z","end":"2026-05-14T13:17:57.719189Z","steps":["trace[1049379713] 'read index received' (duration: 119.695668ms)","trace[1049379713] 'applied index is now lower than readState.Index' (duration: 4.67µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:17:57.719345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.831809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:17:57.719385Z","caller":"traceutil/trace.go:171","msg":"trace[1972672572] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2588; }","duration":"119.90385ms","start":"2026-05-14T13:17:57.599469Z","end":"2026-05-14T13:17:57.719373Z","steps":["trace[1972672572] 'agreement among raft nodes before linearized reading' (duration: 119.820119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:30.356407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.91458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T13:18:30.356471Z","caller":"traceutil/trace.go:171","msg":"trace[204563825] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2767; }","duration":"323.036331ms","start":"2026-05-14T13:18:30.033422Z","end":"2026-05-14T13:18:30.356458Z","steps":["trace[204563825] 'count revisions from in-memory index tree' (duration: 322.810589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:30.356498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:30.033403Z","time spent":"323.089491ms","remote":"127.0.0.1:46178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-14T13:18:30.356705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.465021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T13:18:30.356847Z","caller":"traceutil/trace.go:171","msg":"trace[2075226443] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2767; }","duration":"236.680464ms","start":"2026-05-14T13:18:30.120122Z","end":"2026-05-14T13:18:30.356802Z","steps":["trace[2075226443] 'count revisions from in-memory index tree' (duration: 236.34043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:30.740429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.785033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:18:30.740513Z","caller":"traceutil/trace.go:171","msg":"trace[1792185361] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"302.760807ms","start":"2026-05-14T13:18:30.437737Z","end":"2026-05-14T13:18:30.740497Z","steps":["trace[1792185361] 'process raft request' (duration: 36.829302ms)","trace[1792185361] 'compare' (duration: 265.352868ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:18:30.740551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:30.437696Z","time spent":"302.839107ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:18:31.309977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.816378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:31.310094Z","caller":"traceutil/trace.go:171","msg":"trace[1070948255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2771; }","duration":"293.00294ms","start":"2026-05-14T13:18:31.017068Z","end":"2026-05-14T13:18:31.310071Z","steps":["trace[1070948255] 'range keys from in-memory index tree' (duration: 292.730947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.31014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.459231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:18:31.310258Z","caller":"traceutil/trace.go:171","msg":"trace[1046226157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2771; }","duration":"205.648954ms","start":"2026-05-14T13:18:31.104581Z","end":"2026-05-14T13:18:31.31023Z","steps":["trace[1046226157] 'range keys from in-memory index tree' (duration: 205.176128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.31045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.601788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-05-14T13:18:31.310615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.869937ms","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-14T13:18:31.310655Z","caller":"traceutil/trace.go:171","msg":"trace[1631093384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2771; }","duration":"195.921577ms","start":"2026-05-14T13:18:31.114722Z","end":"2026-05-14T13:18:31.310643Z","steps":["trace[1631093384] 'range keys from in-memory index tree' (duration: 195.773835ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:31.310617Z","caller":"traceutil/trace.go:171","msg":"trace[422479776] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2771; }","duration":"268.845911ms","start":"2026-05-14T13:18:31.041748Z","end":"2026-05-14T13:18:31.310594Z","steps":["trace[422479776] 'range keys from in-memory index tree' (duration: 268.118003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.31091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.249031ms","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-14T13:18:31.310945Z","caller":"traceutil/trace.go:171","msg":"trace[1227207237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2771; }","duration":"196.351873ms","start":"2026-05-14T13:18:31.114584Z","end":"2026-05-14T13:18:31.310936Z","steps":["trace[1227207237] 'range keys from in-memory index tree' (duration: 196.056519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.311065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.859662ms","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-14T13:18:31.311097Z","caller":"traceutil/trace.go:171","msg":"trace[841965749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2771; }","duration":"188.933234ms","start":"2026-05-14T13:18:31.122157Z","end":"2026-05-14T13:18:31.31109Z","steps":["trace[841965749] 'range keys from in-memory index tree' (duration: 188.738241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.311242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.939244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T13:18:31.311266Z","caller":"traceutil/trace.go:171","msg":"trace[710580184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2771; }","duration":"183.976664ms","start":"2026-05-14T13:18:31.127283Z","end":"2026-05-14T13:18:31.31126Z","steps":["trace[710580184] 'range keys from in-memory index tree' (duration: 183.844043ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:31.475639Z","caller":"traceutil/trace.go:171","msg":"trace[1833613959] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"157.476607ms","start":"2026-05-14T13:18:31.318132Z","end":"2026-05-14T13:18:31.475609Z","steps":["trace[1833613959] 'process raft request' (duration: 157.322105ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:31.603314Z","caller":"traceutil/trace.go:171","msg":"trace[234102104] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"283.073002ms","start":"2026-05-14T13:18:31.320215Z","end":"2026-05-14T13:18:31.603288Z","steps":["trace[234102104] 'process raft request' (duration: 283.007201ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:31.603323Z","caller":"traceutil/trace.go:171","msg":"trace[1254961866] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"283.81286ms","start":"2026-05-14T13:18:31.319479Z","end":"2026-05-14T13:18:31.603291Z","steps":["trace[1254961866] 'process raft request' (duration: 283.700499ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:31.603363Z","caller":"traceutil/trace.go:171","msg":"trace[661574492] transaction","detail":"{read_only:false; response_revision:2773; number_of_response:1; }","duration":"285.025755ms","start":"2026-05-14T13:18:31.318238Z","end":"2026-05-14T13:18:31.603263Z","steps":["trace[661574492] 'process raft request' (duration: 284.566329ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:31.603427Z","caller":"traceutil/trace.go:171","msg":"trace[336612244] linearizableReadLoop","detail":"{readStateIndex:2878; appliedIndex:2873; }","duration":"281.21791ms","start":"2026-05-14T13:18:31.32219Z","end":"2026-05-14T13:18:31.603408Z","steps":["trace[336612244] 'read index received' (duration: 153.303068ms)","trace[336612244] 'applied index is now lower than readState.Index' (duration: 127.913942ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:18:31.603646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.430903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-05-14T13:18:31.603692Z","caller":"traceutil/trace.go:171","msg":"trace[1423864166] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2776; }","duration":"281.506044ms","start":"2026-05-14T13:18:31.322176Z","end":"2026-05-14T13:18:31.603682Z","steps":["trace[1423864166] 'agreement among raft nodes before linearized reading' (duration: 281.291821ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:31.603559Z","caller":"traceutil/trace.go:171","msg":"trace[616943407] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"281.434883ms","start":"2026-05-14T13:18:31.322101Z","end":"2026-05-14T13:18:31.603536Z","steps":["trace[616943407] 'process raft request' (duration: 281.1552ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:18.320937Z","caller":"traceutil/trace.go:171","msg":"trace[1058683724] transaction","detail":"{read_only:false; response_revision:3413; number_of_response:1; }","duration":"127.398261ms","start":"2026-05-14T13:20:18.193513Z","end":"2026-05-14T13:20:18.320912Z","steps":["trace[1058683724] 'process raft request' (duration: 127.323988ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:18.321021Z","caller":"traceutil/trace.go:171","msg":"trace[1776814345] transaction","detail":"{read_only:false; response_revision:3412; number_of_response:1; }","duration":"128.521734ms","start":"2026-05-14T13:20:18.19249Z","end":"2026-05-14T13:20:18.321012Z","steps":["trace[1776814345] 'process raft request' (duration: 128.215105ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:18.321044Z","caller":"traceutil/trace.go:171","msg":"trace[1939609270] transaction","detail":"{read_only:false; response_revision:3414; number_of_response:1; }","duration":"125.919926ms","start":"2026-05-14T13:20:18.1951Z","end":"2026-05-14T13:20:18.32102Z","steps":["trace[1939609270] 'process raft request' (duration: 125.776982ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:18.320951Z","caller":"traceutil/trace.go:171","msg":"trace[1785833901] transaction","detail":"{read_only:false; response_revision:3411; number_of_response:1; }","duration":"129.773332ms","start":"2026-05-14T13:20:18.191146Z","end":"2026-05-14T13:20:18.32092Z","steps":["trace[1785833901] 'process raft request' (duration: 50.330623ms)","trace[1785833901] 'compare' (duration: 78.904802ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:22:15.8768Z","caller":"traceutil/trace.go:171","msg":"trace[539918474] transaction","detail":"{read_only:false; response_revision:4637; number_of_response:1; }","duration":"201.850579ms","start":"2026-05-14T13:22:15.674934Z","end":"2026-05-14T13:22:15.876785Z","steps":["trace[539918474] 'process raft request' (duration: 201.633185ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:15.877068Z","caller":"traceutil/trace.go:171","msg":"trace[543503695] linearizableReadLoop","detail":"{readStateIndex:4813; appliedIndex:4812; }","duration":"199.881899ms","start":"2026-05-14T13:22:15.676867Z","end":"2026-05-14T13:22:15.876749Z","steps":["trace[543503695] 'read index received' (duration: 199.672485ms)","trace[543503695] 'applied index is now lower than readState.Index' (duration: 208.574µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:22:15.87707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.180925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-05-14T13:22:15.877235Z","caller":"traceutil/trace.go:171","msg":"trace[844301371] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4637; }","duration":"200.359189ms","start":"2026-05-14T13:22:15.676848Z","end":"2026-05-14T13:22:15.877207Z","steps":["trace[844301371] 'agreement among raft nodes before linearized reading' (duration: 199.997132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:15.878029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.711571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-14T13:22:15.878145Z","caller":"traceutil/trace.go:171","msg":"trace[1413249555] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4638; }","duration":"170.875014ms","start":"2026-05-14T13:22:15.70724Z","end":"2026-05-14T13:22:15.878115Z","steps":["trace[1413249555] 'agreement among raft nodes before linearized reading' (duration: 170.553268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:21.516044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869392629448313,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T13:22:21.564728Z","caller":"traceutil/trace.go:171","msg":"trace[1211816645] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"569.972853ms","start":"2026-05-14T13:22:20.994718Z","end":"2026-05-14T13:22:21.56469Z","steps":["trace[1211816645] 'process raft request' (duration: 569.904662ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:21.564834Z","caller":"traceutil/trace.go:171","msg":"trace[89797857] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"579.875316ms","start":"2026-05-14T13:22:20.984912Z","end":"2026-05-14T13:22:21.564787Z","steps":["trace[89797857] 'process raft request' (duration: 579.505169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:21.565021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:20.9947Z","time spent":"570.136206ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:22:21.56508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:20.984886Z","time spent":"580.084801ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:22:21.969553Z","caller":"traceutil/trace.go:171","msg":"trace[1248677461] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"681.701216ms","start":"2026-05-14T13:22:21.287819Z","end":"2026-05-14T13:22:21.96952Z","steps":["trace[1248677461] 'process raft request' (duration: 588.139387ms)","trace[1248677461] 'compare' (duration: 93.138281ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:22:21.969711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:21.287797Z","time spent":"681.827319ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:22:21.970117Z","caller":"traceutil/trace.go:171","msg":"trace[709771179] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"659.565554ms","start":"2026-05-14T13:22:21.310516Z","end":"2026-05-14T13:22:21.970082Z","steps":["trace[709771179] 'process raft request' (duration: 658.925321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:21.970301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:21.310481Z","time spent":"659.736047ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:22:21.970659Z","caller":"traceutil/trace.go:171","msg":"trace[1779060618] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"623.264685ms","start":"2026-05-14T13:22:21.347381Z","end":"2026-05-14T13:22:21.970646Z","steps":["trace[1779060618] 'process raft request' (duration: 622.429698ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:21.970647Z","caller":"traceutil/trace.go:171","msg":"trace[306236250] linearizableReadLoop","detail":"{readStateIndex:4925; appliedIndex:4920; }","duration":"955.410297ms","start":"2026-05-14T13:22:21.015211Z","end":"2026-05-14T13:22:21.970622Z","steps":["trace[306236250] 'read index received' (duration: 549.264017ms)","trace[306236250] 'applied index is now lower than readState.Index' (duration: 406.1449ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:22:21.970732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:21.347363Z","time spent":"623.334517ms","remote":"127.0.0.1:45942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":878,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T13:22:21.971033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.805845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:22:21.9712Z","caller":"traceutil/trace.go:171","msg":"trace[1309357502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4747; }","duration":"955.98759ms","start":"2026-05-14T13:22:21.015151Z","end":"2026-05-14T13:22:21.971139Z","steps":["trace[1309357502] 'agreement among raft nodes before linearized reading' (duration: 955.691594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:21.971413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:21.015133Z","time spent":"956.263335ms","remote":"127.0.0.1:45886","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-14T13:22:22.040308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.364524ms","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":"warn","ts":"2026-05-14T13:22:22.040393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.775927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46d62250-ade9-4fc9-96d1-2d91b0606f3d\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-14T13:22:22.040449Z","caller":"traceutil/trace.go:171","msg":"trace[1148839565] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-46d62250-ade9-4fc9-96d1-2d91b0606f3d; range_end:; response_count:1; response_revision:4748; }","duration":"699.882029ms","start":"2026-05-14T13:22:21.340553Z","end":"2026-05-14T13:22:22.040435Z","steps":["trace[1148839565] 'agreement among raft nodes before linearized reading' (duration: 699.696656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:22.040485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:21.340534Z","time spent":"699.942491ms","remote":"127.0.0.1:46046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46d62250-ade9-4fc9-96d1-2d91b0606f3d\" "} {"level":"info","ts":"2026-05-14T13:22:22.040407Z","caller":"traceutil/trace.go:171","msg":"trace[1647138274] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4748; }","duration":"489.479756ms","start":"2026-05-14T13:22:21.550912Z","end":"2026-05-14T13:22:22.040392Z","steps":["trace[1647138274] 'agreement among raft nodes before linearized reading' (duration: 489.315483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:22.04063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.570148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:22:22.040701Z","caller":"traceutil/trace.go:171","msg":"trace[482686988] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4748; }","duration":"979.711811ms","start":"2026-05-14T13:22:21.060971Z","end":"2026-05-14T13:22:22.040682Z","steps":["trace[482686988] 'agreement among raft nodes before linearized reading' (duration: 979.593989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:22.040738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:21.060952Z","time spent":"979.776853ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-05-14T13:22:22.040715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:21.550891Z","time spent":"489.788204ms","remote":"127.0.0.1:52150","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-14T13:22:22.040637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.007703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46d62250-ade9-4fc9-96d1-2d91b0606f3d\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-14T13:22:22.041107Z","caller":"traceutil/trace.go:171","msg":"trace[1300330650] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"141.931873ms","start":"2026-05-14T13:22:21.899159Z","end":"2026-05-14T13:22:22.041091Z","steps":["trace[1300330650] 'process raft request' (duration: 140.933813ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:22.040921Z","caller":"traceutil/trace.go:171","msg":"trace[530444502] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-46d62250-ade9-4fc9-96d1-2d91b0606f3d; range_end:; response_count:1; response_revision:4748; }","duration":"900.36301ms","start":"2026-05-14T13:22:21.140528Z","end":"2026-05-14T13:22:22.040891Z","steps":["trace[530444502] 'agreement among raft nodes before linearized reading' (duration: 899.841389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:22.041354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:21.140508Z","time spent":"900.823799ms","remote":"127.0.0.1:46046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46d62250-ade9-4fc9-96d1-2d91b0606f3d\" "} {"level":"info","ts":"2026-05-14T13:22:24.985324Z","caller":"traceutil/trace.go:171","msg":"trace[1824364370] linearizableReadLoop","detail":"{readStateIndex:4964; appliedIndex:4963; }","duration":"109.355761ms","start":"2026-05-14T13:22:24.875942Z","end":"2026-05-14T13:22:24.985298Z","steps":["trace[1824364370] 'read index received' (duration: 109.130497ms)","trace[1824364370] 'applied index is now lower than readState.Index' (duration: 224.324µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:22:24.985397Z","caller":"traceutil/trace.go:171","msg":"trace[1793669826] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"120.013579ms","start":"2026-05-14T13:22:24.865348Z","end":"2026-05-14T13:22:24.985361Z","steps":["trace[1793669826] 'process raft request' (duration: 119.788504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:24.985582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.615576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T13:22:24.985624Z","caller":"traceutil/trace.go:171","msg":"trace[1057864844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4785; }","duration":"109.682197ms","start":"2026-05-14T13:22:24.875932Z","end":"2026-05-14T13:22:24.985614Z","steps":["trace[1057864844] 'agreement among raft nodes before linearized reading' (duration: 109.501934ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:26.771306Z","caller":"traceutil/trace.go:171","msg":"trace[1197605169] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"160.016168ms","start":"2026-05-14T13:22:26.611263Z","end":"2026-05-14T13:22:26.77128Z","steps":["trace[1197605169] 'process raft request' (duration: 159.911726ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:26.771304Z","caller":"traceutil/trace.go:171","msg":"trace[1889820289] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"346.268192ms","start":"2026-05-14T13:22:26.424983Z","end":"2026-05-14T13:22:26.771251Z","steps":["trace[1889820289] 'process raft request' (duration: 322.865241ms)","trace[1889820289] 'compare' (duration: 22.754659ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:22:26.77189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:26.424962Z","time spent":"346.442005ms","remote":"127.0.0.1:46046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9564,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:22:35.531586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869392629448700,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T13:22:35.980342Z","caller":"traceutil/trace.go:171","msg":"trace[323699683] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"954.704441ms","start":"2026-05-14T13:22:35.02562Z","end":"2026-05-14T13:22:35.980325Z","steps":["trace[323699683] 'process raft request' (duration: 954.477157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:35.98045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:35.025604Z","time spent":"954.789893ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":532,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:22:36.009683Z","caller":"traceutil/trace.go:171","msg":"trace[1221260882] linearizableReadLoop","detail":"{readStateIndex:5099; appliedIndex:5096; }","duration":"978.776444ms","start":"2026-05-14T13:22:35.030887Z","end":"2026-05-14T13:22:36.009663Z","steps":["trace[1221260882] 'read index received' (duration: 949.278168ms)","trace[1221260882] 'applied index is now lower than readState.Index' (duration: 29.494895ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:22:36.009762Z","caller":"traceutil/trace.go:171","msg":"trace[674950174] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"700.4284ms","start":"2026-05-14T13:22:35.309315Z","end":"2026-05-14T13:22:36.009743Z","steps":["trace[674950174] 'process raft request' (duration: 700.186426ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:36.009814Z","caller":"traceutil/trace.go:171","msg":"trace[836396228] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"529.447543ms","start":"2026-05-14T13:22:35.480312Z","end":"2026-05-14T13:22:36.009759Z","steps":["trace[836396228] 'process raft request' (duration: 529.307471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:36.00986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.954748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:22:36.009887Z","caller":"traceutil/trace.go:171","msg":"trace[329006548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4915; }","duration":"979.024979ms","start":"2026-05-14T13:22:35.030855Z","end":"2026-05-14T13:22:36.00988Z","steps":["trace[329006548] 'agreement among raft nodes before linearized reading' (duration: 978.895446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:36.009892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:35.309297Z","time spent":"700.529613ms","remote":"127.0.0.1:46032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11904,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:22:36.009906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:35.030843Z","time spent":"979.058789ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:22:36.009935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:35.480295Z","time spent":"529.566045ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:22:36.12942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.567994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:22:36.129853Z","caller":"traceutil/trace.go:171","msg":"trace[1773930118] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"107.81062ms","start":"2026-05-14T13:22:36.021946Z","end":"2026-05-14T13:22:36.129756Z","steps":["trace[1773930118] 'process raft request' (duration: 107.752879ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:36.129869Z","caller":"traceutil/trace.go:171","msg":"trace[1970292823] linearizableReadLoop","detail":"{readStateIndex:5100; appliedIndex:5099; }","duration":"120.119042ms","start":"2026-05-14T13:22:36.009728Z","end":"2026-05-14T13:22:36.129847Z","steps":["trace[1970292823] 'read index received' (duration: 19.011968ms)","trace[1970292823] 'applied index is now lower than readState.Index' (duration: 101.104734ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:22:36.129906Z","caller":"traceutil/trace.go:171","msg":"trace[1676134867] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"113.923865ms","start":"2026-05-14T13:22:36.015959Z","end":"2026-05-14T13:22:36.129882Z","steps":["trace[1676134867] 'process raft request' (duration: 113.585369ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:36.129892Z","caller":"traceutil/trace.go:171","msg":"trace[1802756804] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"129.644431ms","start":"2026-05-14T13:22:36.000197Z","end":"2026-05-14T13:22:36.129841Z","steps":["trace[1802756804] 'process raft request' (duration: 28.557167ms)","trace[1802756804] 'compare' (duration: 100.390241ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:22:36.130029Z","caller":"traceutil/trace.go:171","msg":"trace[201391760] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"110.078893ms","start":"2026-05-14T13:22:36.019936Z","end":"2026-05-14T13:22:36.130015Z","steps":["trace[201391760] 'process raft request' (duration: 109.704366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:36.13013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.047356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-85f848b47-h6sn4\" ","response":"range_response_count:1 size:11840"} {"level":"info","ts":"2026-05-14T13:22:36.130177Z","caller":"traceutil/trace.go:171","msg":"trace[1717890717] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-85f848b47-h6sn4; range_end:; response_count:1; response_revision:4919; }","duration":"240.143837ms","start":"2026-05-14T13:22:35.89002Z","end":"2026-05-14T13:22:36.130163Z","steps":["trace[1717890717] 'agreement among raft nodes before linearized reading' (duration: 239.927183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:36.130261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.192845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-14T13:22:36.130329Z","caller":"traceutil/trace.go:171","msg":"trace[1800785983] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4919; }","duration":"998.276797ms","start":"2026-05-14T13:22:35.132027Z","end":"2026-05-14T13:22:36.130304Z","steps":["trace[1800785983] 'agreement among raft nodes before linearized reading' (duration: 997.998242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:36.130336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.142836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-14T13:22:36.130375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:35.132017Z","time spent":"998.344728ms","remote":"127.0.0.1:55924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3338,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-05-14T13:22:36.130393Z","caller":"traceutil/trace.go:171","msg":"trace[390725403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4919; }","duration":"508.201057ms","start":"2026-05-14T13:22:35.622178Z","end":"2026-05-14T13:22:36.130379Z","steps":["trace[390725403] 'agreement among raft nodes before linearized reading' (duration: 508.053804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:36.130436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:35.622126Z","time spent":"508.294348ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T13:22:36.13052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085222718s","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-14T13:22:36.130557Z","caller":"traceutil/trace.go:171","msg":"trace[525697514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4919; }","duration":"1.085278759s","start":"2026-05-14T13:22:35.04527Z","end":"2026-05-14T13:22:36.130548Z","steps":["trace[525697514] 'agreement among raft nodes before linearized reading' (duration: 1.085193957s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:36.130591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:35.045259Z","time spent":"1.085325439s","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T13:22:36.130443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043241026s","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-14T13:22:36.130696Z","caller":"traceutil/trace.go:171","msg":"trace[610871723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4919; }","duration":"1.04350209s","start":"2026-05-14T13:22:35.087173Z","end":"2026-05-14T13:22:36.130675Z","steps":["trace[610871723] 'agreement among raft nodes before linearized reading' (duration: 1.043200974s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:36.13076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:35.087162Z","time spent":"1.043580412s","remote":"127.0.0.1:46108","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-14T13:22:36.130748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096870488s","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-14T13:22:36.130822Z","caller":"traceutil/trace.go:171","msg":"trace[1534701999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4919; }","duration":"1.096972479s","start":"2026-05-14T13:22:35.033833Z","end":"2026-05-14T13:22:36.130805Z","steps":["trace[1534701999] 'agreement among raft nodes before linearized reading' (duration: 1.096642733s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:36.130884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:35.033826Z","time spent":"1.097042561s","remote":"127.0.0.1:46108","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-14T13:22:36.131011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.579802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T13:22:36.131133Z","caller":"traceutil/trace.go:171","msg":"trace[137773252] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4919; }","duration":"660.821476ms","start":"2026-05-14T13:22:35.47029Z","end":"2026-05-14T13:22:36.131112Z","steps":["trace[137773252] 'agreement among raft nodes before linearized reading' (duration: 660.168604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:36.131237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:22:35.47027Z","time spent":"660.952309ms","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":10,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-05-14T13:22:36.412349Z","caller":"traceutil/trace.go:171","msg":"trace[759729178] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"182.233982ms","start":"2026-05-14T13:22:36.230083Z","end":"2026-05-14T13:22:36.412317Z","steps":["trace[759729178] 'process raft request' (duration: 181.912716ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:36.506808Z","caller":"traceutil/trace.go:171","msg":"trace[850222088] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5109; }","duration":"274.989239ms","start":"2026-05-14T13:22:36.231791Z","end":"2026-05-14T13:22:36.506781Z","steps":["trace[850222088] 'read index received' (duration: 180.241365ms)","trace[850222088] 'applied index is now lower than readState.Index' (duration: 94.746824ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:22:36.507012Z","caller":"traceutil/trace.go:171","msg":"trace[169125444] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"273.217666ms","start":"2026-05-14T13:22:36.23376Z","end":"2026-05-14T13:22:36.506977Z","steps":["trace[169125444] 'process raft request' (duration: 272.871328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:36.507122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.304005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-05-14T13:22:36.507162Z","caller":"traceutil/trace.go:171","msg":"trace[842361570] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4928; }","duration":"275.375026ms","start":"2026-05-14T13:22:36.231774Z","end":"2026-05-14T13:22:36.507149Z","steps":["trace[842361570] 'agreement among raft nodes before linearized reading' (duration: 275.117931ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:36.506952Z","caller":"traceutil/trace.go:171","msg":"trace[165955567] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"275.152332ms","start":"2026-05-14T13:22:36.23171Z","end":"2026-05-14T13:22:36.506862Z","steps":["trace[165955567] 'process raft request' (duration: 255.90648ms)","trace[165955567] 'compare' (duration: 18.6076ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:22:36.786434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.601605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-05-14T13:22:36.786516Z","caller":"traceutil/trace.go:171","msg":"trace[1715462666] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4931; }","duration":"168.751557ms","start":"2026-05-14T13:22:36.617745Z","end":"2026-05-14T13:22:36.786497Z","steps":["trace[1715462666] 'range keys from in-memory index tree' (duration: 168.397001ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:23:14.323289Z","caller":"traceutil/trace.go:171","msg":"trace[802689466] linearizableReadLoop","detail":"{readStateIndex:5455; appliedIndex:5454; }","duration":"102.151053ms","start":"2026-05-14T13:23:14.221116Z","end":"2026-05-14T13:23:14.323267Z","steps":["trace[802689466] 'read index received' (duration: 102.030711ms)","trace[802689466] 'applied index is now lower than readState.Index' (duration: 119.232µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:23:14.3235Z","caller":"traceutil/trace.go:171","msg":"trace[1290136395] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"151.549549ms","start":"2026-05-14T13:23:14.171928Z","end":"2026-05-14T13:23:14.323478Z","steps":["trace[1290136395] 'process raft request' (duration: 151.194683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:23:14.323541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.404757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-05-14T13:23:14.323611Z","caller":"traceutil/trace.go:171","msg":"trace[382702994] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5262; }","duration":"102.518228ms","start":"2026-05-14T13:23:14.221082Z","end":"2026-05-14T13:23:14.3236Z","steps":["trace[382702994] 'agreement among raft nodes before linearized reading' (duration: 102.270134ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:23:30.720085Z","caller":"traceutil/trace.go:171","msg":"trace[1029997816] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"128.446563ms","start":"2026-05-14T13:23:30.591606Z","end":"2026-05-14T13:23:30.720053Z","steps":["trace[1029997816] 'process raft request' (duration: 105.978085ms)","trace[1029997816] 'compare' (duration: 22.341756ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:23:59.2029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2919} {"level":"info","ts":"2026-05-14T13:23:59.577424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2919,"took":"372.986626ms","hash":1089831366,"current-db-size-bytes":52715520,"current-db-size":"53 MB","current-db-size-in-use-bytes":28897280,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-14T13:23:59.577568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1089831366,"revision":2919,"compact-revision":-1} {"level":"info","ts":"2026-05-14T13:28:59.20949Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5588} {"level":"info","ts":"2026-05-14T13:28:59.298879Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5588,"took":"87.415806ms","hash":237276872,"current-db-size-bytes":52715520,"current-db-size":"53 MB","current-db-size-in-use-bytes":16642048,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-05-14T13:28:59.298936Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":237276872,"revision":5588,"compact-revision":2919}