{"level":"warn","ts":"2026-08-24T19:36:41.838824Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T19:36:41.838911Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.74:2380","--initial-cluster=instance=https://199.19.213.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-24T19:36:41.838981Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T19:36:41.83899Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.74:2380"]} {"level":"info","ts":"2026-08-24T19:36:41.839007Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T19:36:41.839822Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"]} {"level":"info","ts":"2026-08-24T19:36:41.839929Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.74:2380"],"listen-peer-urls":["https://199.19.213.74:2380"],"advertise-client-urls":["https://199.19.213.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-24T19:36:41.844094Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.911921ms"} {"level":"info","ts":"2026-08-24T19:36:41.853845Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d4171f3ea72ed207","cluster-id":"d40b55e346dc63dc"} {"level":"info","ts":"2026-08-24T19:36:41.853975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T19:36:41.854012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became follower at term 0"} {"level":"info","ts":"2026-08-24T19:36:41.854039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d4171f3ea72ed207 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T19:36:41.854058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became follower at term 1"} {"level":"info","ts":"2026-08-24T19:36:41.854123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=(15282718214458364423)"} {"level":"warn","ts":"2026-08-24T19:36:41.861857Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T19:36:41.863335Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T19:36:41.864643Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-24T19:36:41.870182Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d4171f3ea72ed207","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T19:36:41.870468Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d4171f3ea72ed207","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T19:36:41.870573Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T19:36:41.870613Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T19:36:41.870625Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T19:36:41.87116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=(15282718214458364423)"} {"level":"info","ts":"2026-08-24T19:36:41.871264Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d40b55e346dc63dc","local-member-id":"d4171f3ea72ed207","added-peer-id":"d4171f3ea72ed207","added-peer-peer-urls":["https://199.19.213.74:2380"]} {"level":"info","ts":"2026-08-24T19:36:41.87505Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T19:36:41.875129Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.74:2380"} {"level":"info","ts":"2026-08-24T19:36:41.875173Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.74:2380"} {"level":"info","ts":"2026-08-24T19:36:41.875239Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d4171f3ea72ed207","initial-advertise-peer-urls":["https://199.19.213.74:2380"],"listen-peer-urls":["https://199.19.213.74:2380"],"advertise-client-urls":["https://199.19.213.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T19:36:41.875276Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T19:36:41.954488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T19:36:41.95453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T19:36:41.954553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 received MsgPreVoteResp from d4171f3ea72ed207 at term 1"} {"level":"info","ts":"2026-08-24T19:36:41.954565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became candidate at term 2"} {"level":"info","ts":"2026-08-24T19:36:41.954573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 received MsgVoteResp from d4171f3ea72ed207 at term 2"} {"level":"info","ts":"2026-08-24T19:36:41.954592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became leader at term 2"} {"level":"info","ts":"2026-08-24T19:36:41.954606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d4171f3ea72ed207 elected leader d4171f3ea72ed207 at term 2"} {"level":"info","ts":"2026-08-24T19:36:41.955483Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T19:36:41.956062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T19:36:41.956063Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d4171f3ea72ed207","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.74:2379]}","request-path":"/0/members/d4171f3ea72ed207/attributes","cluster-id":"d40b55e346dc63dc","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T19:36:41.956268Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T19:36:41.956337Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d40b55e346dc63dc","local-member-id":"d4171f3ea72ed207","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T19:36:41.95644Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T19:36:41.956461Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T19:36:41.956493Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T19:36:41.956502Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T19:36:41.958725Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T19:36:41.958745Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.74:2379"} {"level":"info","ts":"2026-08-24T19:36:43.871749Z","caller":"traceutil/trace.go:171","msg":"trace[1959580524] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"116.846047ms","start":"2026-08-24T19:36:43.754877Z","end":"2026-08-24T19:36:43.871723Z","steps":["trace[1959580524] 'read index received' (duration: 116.590357ms)","trace[1959580524] 'applied index is now lower than readState.Index' (duration: 254.56µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:36:43.871787Z","caller":"traceutil/trace.go:171","msg":"trace[1294001336] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"118.62851ms","start":"2026-08-24T19:36:43.753134Z","end":"2026-08-24T19:36:43.871762Z","steps":["trace[1294001336] 'process raft request' (duration: 118.37778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:43.872099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.095668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T19:36:43.872261Z","caller":"traceutil/trace.go:171","msg":"trace[179635196] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"117.334858ms","start":"2026-08-24T19:36:43.754873Z","end":"2026-08-24T19:36:43.872208Z","steps":["trace[179635196] 'agreement among raft nodes before linearized reading' (duration: 116.992843ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:36:44.216338Z","caller":"traceutil/trace.go:171","msg":"trace[2125607942] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"378.233859ms","start":"2026-08-24T19:36:43.838096Z","end":"2026-08-24T19:36:44.21633Z","steps":["trace[2125607942] 'process raft request' (duration: 378.154036ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:36:44.216469Z","caller":"traceutil/trace.go:171","msg":"trace[46247430] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"378.574434ms","start":"2026-08-24T19:36:43.837872Z","end":"2026-08-24T19:36:44.216447Z","steps":["trace[46247430] 'process raft request' (duration: 378.357225ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:36:44.216588Z","caller":"traceutil/trace.go:171","msg":"trace[1754811820] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"344.627547ms","start":"2026-08-24T19:36:43.871843Z","end":"2026-08-24T19:36:44.216471Z","steps":["trace[1754811820] 'read index received' (duration: 344.242131ms)","trace[1754811820] 'applied index is now lower than readState.Index' (duration: 384.276µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:36:44.216707Z","caller":"traceutil/trace.go:171","msg":"trace[946866720] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"368.468319ms","start":"2026-08-24T19:36:43.848161Z","end":"2026-08-24T19:36:44.216629Z","steps":["trace[946866720] 'process raft request' (duration: 368.164286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:44.216907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.838082Z","time spent":"378.307612ms","remote":"127.0.0.1:39668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T19:36:44.216935Z","caller":"traceutil/trace.go:171","msg":"trace[1972838776] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"378.74309ms","start":"2026-08-24T19:36:43.838159Z","end":"2026-08-24T19:36:44.216902Z","steps":["trace[1972838776] 'process raft request' (duration: 378.106124ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:36:44.217016Z","caller":"traceutil/trace.go:171","msg":"trace[1265130958] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"377.845303ms","start":"2026-08-24T19:36:43.839154Z","end":"2026-08-24T19:36:44.216999Z","steps":["trace[1265130958] 'process raft request' (duration: 377.148915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:44.217087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.848135Z","time spent":"368.649675ms","remote":"127.0.0.1:39358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T19:36:44.217093Z","caller":"traceutil/trace.go:171","msg":"trace[1657119996] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"378.602055ms","start":"2026-08-24T19:36:43.838478Z","end":"2026-08-24T19:36:44.21708Z","steps":["trace[1657119996] 'process raft request' (duration: 377.802352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:44.217136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.839141Z","time spent":"377.951587ms","remote":"127.0.0.1:39566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T19:36:44.217318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.201448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T19:36:44.217359Z","caller":"traceutil/trace.go:171","msg":"trace[47088276] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"378.247509ms","start":"2026-08-24T19:36:43.839102Z","end":"2026-08-24T19:36:44.217349Z","steps":["trace[47088276] 'agreement among raft nodes before linearized reading' (duration: 378.180547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:44.217367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.14819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.74\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-24T19:36:44.217392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.839059Z","time spent":"378.324493ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-08-24T19:36:44.217405Z","caller":"traceutil/trace.go:171","msg":"trace[1364004994] range","detail":"{range_begin:/registry/masterleases/199.19.213.74; range_end:; response_count:0; response_revision:10; }","duration":"382.222864ms","start":"2026-08-24T19:36:43.83517Z","end":"2026-08-24T19:36:44.217392Z","steps":["trace[1364004994] 'agreement among raft nodes before linearized reading' (duration: 382.175461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:44.217414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.433823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"warn","ts":"2026-08-24T19:36:44.217021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.838144Z","time spent":"378.829745ms","remote":"127.0.0.1:39668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T19:36:44.217474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.835149Z","time spent":"382.304637ms","remote":"127.0.0.1:39146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.19.213.74\" "} {"level":"info","ts":"2026-08-24T19:36:44.217506Z","caller":"traceutil/trace.go:171","msg":"trace[758750851] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"462.57192ms","start":"2026-08-24T19:36:43.754917Z","end":"2026-08-24T19:36:44.217489Z","steps":["trace[758750851] 'agreement among raft nodes before linearized reading' (duration: 462.313149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:44.217563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.754897Z","time spent":"462.657263ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-24T19:36:44.217592Z","caller":"traceutil/trace.go:171","msg":"trace[740595912] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"364.18904ms","start":"2026-08-24T19:36:43.853383Z","end":"2026-08-24T19:36:44.217572Z","steps":["trace[740595912] 'process raft request' (duration: 362.970141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:44.217169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.838433Z","time spent":"378.692668ms","remote":"127.0.0.1:39668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T19:36:44.217704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.853368Z","time spent":"364.286075ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T19:36:44.217309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.136381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-24T19:36:44.217066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.837862Z","time spent":"378.689188ms","remote":"127.0.0.1:39668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T19:36:44.21783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.057108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T19:36:44.217876Z","caller":"traceutil/trace.go:171","msg":"trace[976952629] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"331.136702ms","start":"2026-08-24T19:36:43.886726Z","end":"2026-08-24T19:36:44.217863Z","steps":["trace[976952629] 'agreement among raft nodes before linearized reading' (duration: 331.061828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:44.217919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.886707Z","time spent":"331.200655ms","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-08-24T19:36:44.217996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.067125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-24T19:36:44.218087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.115518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T19:36:44.21814Z","caller":"traceutil/trace.go:171","msg":"trace[313779834] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"329.200672ms","start":"2026-08-24T19:36:43.888928Z","end":"2026-08-24T19:36:44.218129Z","steps":["trace[313779834] 'agreement among raft nodes before linearized reading' (duration: 329.132909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:44.218173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.888905Z","time spent":"329.262164ms","remote":"127.0.0.1:39236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-24T19:36:44.217836Z","caller":"traceutil/trace.go:171","msg":"trace[960916461] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"461.677242ms","start":"2026-08-24T19:36:43.75615Z","end":"2026-08-24T19:36:44.217827Z","steps":["trace[960916461] 'agreement among raft nodes before linearized reading' (duration: 461.051037ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:36:44.2181Z","caller":"traceutil/trace.go:171","msg":"trace[20440202] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"380.400418ms","start":"2026-08-24T19:36:43.837673Z","end":"2026-08-24T19:36:44.218074Z","steps":["trace[20440202] 'agreement among raft nodes before linearized reading' (duration: 379.626367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:44.218407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.837656Z","time spent":"380.741962ms","remote":"127.0.0.1:39236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-24T19:36:44.218318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.756145Z","time spent":"462.133881ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-08-24T19:36:44.21802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.368723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T19:36:44.218682Z","caller":"traceutil/trace.go:171","msg":"trace[566404471] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"317.038792ms","start":"2026-08-24T19:36:43.901623Z","end":"2026-08-24T19:36:44.218662Z","steps":["trace[566404471] 'agreement among raft nodes before linearized reading' (duration: 316.372734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:44.218744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.901617Z","time spent":"317.112924ms","remote":"127.0.0.1:39236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-24T19:36:44.216298Z","caller":"traceutil/trace.go:171","msg":"trace[1835833392] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"379.269531ms","start":"2026-08-24T19:36:43.837004Z","end":"2026-08-24T19:36:44.216273Z","steps":["trace[1835833392] 'process raft request' (duration: 379.125416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:36:44.220762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:36:43.836988Z","time spent":"383.741565ms","remote":"127.0.0.1:39668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T19:37:17.853853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.241437ms","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-08-24T19:37:17.853873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.432166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:17.853951Z","caller":"traceutil/trace.go:171","msg":"trace[417590524] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:469; }","duration":"212.545391ms","start":"2026-08-24T19:37:17.64139Z","end":"2026-08-24T19:37:17.853935Z","steps":["trace[417590524] 'range keys from in-memory index tree' (duration: 212.392545ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:17.853951Z","caller":"traceutil/trace.go:171","msg":"trace[1818415224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"219.368381ms","start":"2026-08-24T19:37:17.634546Z","end":"2026-08-24T19:37:17.853914Z","steps":["trace[1818415224] 'range keys from in-memory index tree' (duration: 219.106673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:17.853975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.695105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T19:37:17.854029Z","caller":"traceutil/trace.go:171","msg":"trace[229187027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"212.779299ms","start":"2026-08-24T19:37:17.641235Z","end":"2026-08-24T19:37:17.854014Z","steps":["trace[229187027] 'range keys from in-memory index tree' (duration: 212.618012ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:21.683993Z","caller":"traceutil/trace.go:171","msg":"trace[1530915835] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"437.315225ms","start":"2026-08-24T19:37:21.246659Z","end":"2026-08-24T19:37:21.683975Z","steps":["trace[1530915835] 'read index received' (duration: 436.811499ms)","trace[1530915835] 'applied index is now lower than readState.Index' (duration: 503.066µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T19:37:21.684222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.01646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T19:37:21.684286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.936987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:21.684305Z","caller":"traceutil/trace.go:171","msg":"trace[1294431570] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:555; }","duration":"306.117783ms","start":"2026-08-24T19:37:21.378156Z","end":"2026-08-24T19:37:21.684274Z","steps":["trace[1294431570] 'agreement among raft nodes before linearized reading' (duration: 305.971849ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:21.684321Z","caller":"traceutil/trace.go:171","msg":"trace[1358056607] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:555; }","duration":"249.00651ms","start":"2026-08-24T19:37:21.435305Z","end":"2026-08-24T19:37:21.684312Z","steps":["trace[1358056607] 'agreement among raft nodes before linearized reading' (duration: 248.928777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:21.684344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:21.378143Z","time spent":"306.192485ms","remote":"127.0.0.1:47904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-08-24T19:37:21.684286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.241423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:21.684413Z","caller":"traceutil/trace.go:171","msg":"trace[1226281739] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:555; }","duration":"215.379718ms","start":"2026-08-24T19:37:21.468996Z","end":"2026-08-24T19:37:21.684375Z","steps":["trace[1226281739] 'agreement among raft nodes before linearized reading' (duration: 215.228774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:21.684507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.502553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:21.684525Z","caller":"traceutil/trace.go:171","msg":"trace[1556247376] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:555; }","duration":"215.532484ms","start":"2026-08-24T19:37:21.468988Z","end":"2026-08-24T19:37:21.68452Z","steps":["trace[1556247376] 'agreement among raft nodes before linearized reading' (duration: 215.504213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:21.684567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.591025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:21.684582Z","caller":"traceutil/trace.go:171","msg":"trace[1977828251] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:555; }","duration":"215.628717ms","start":"2026-08-24T19:37:21.46895Z","end":"2026-08-24T19:37:21.684579Z","steps":["trace[1977828251] 'agreement among raft nodes before linearized reading' (duration: 215.606156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:21.68462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.074952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:21.68464Z","caller":"traceutil/trace.go:171","msg":"trace[579772326] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:555; }","duration":"249.106363ms","start":"2026-08-24T19:37:21.435531Z","end":"2026-08-24T19:37:21.684637Z","steps":["trace[579772326] 'agreement among raft nodes before linearized reading' (duration: 249.078122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:21.684633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.355281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:21.684706Z","caller":"traceutil/trace.go:171","msg":"trace[1925761418] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:555; }","duration":"249.468204ms","start":"2026-08-24T19:37:21.435229Z","end":"2026-08-24T19:37:21.684697Z","steps":["trace[1925761418] 'agreement among raft nodes before linearized reading' (duration: 249.361841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:21.68479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.537896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:21.684811Z","caller":"traceutil/trace.go:171","msg":"trace[2115076996] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:555; }","duration":"306.568447ms","start":"2026-08-24T19:37:21.378238Z","end":"2026-08-24T19:37:21.684806Z","steps":["trace[2115076996] 'agreement among raft nodes before linearized reading' (duration: 306.538726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:21.684825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:21.378234Z","time spent":"306.586888ms","remote":"127.0.0.1:47904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-08-24T19:37:21.68487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.365601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:21.684885Z","caller":"traceutil/trace.go:171","msg":"trace[1755718355] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:555; }","duration":"306.390891ms","start":"2026-08-24T19:37:21.37849Z","end":"2026-08-24T19:37:21.684881Z","steps":["trace[1755718355] 'agreement among raft nodes before linearized reading' (duration: 306.369431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:21.684897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:21.378484Z","time spent":"306.409542ms","remote":"127.0.0.1:47904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-08-24T19:37:21.68497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.316346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T19:37:21.685006Z","caller":"traceutil/trace.go:171","msg":"trace[1824085289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"438.389568ms","start":"2026-08-24T19:37:21.246609Z","end":"2026-08-24T19:37:21.684999Z","steps":["trace[1824085289] 'agreement among raft nodes before linearized reading' (duration: 438.181381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:21.685051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.671172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T19:37:21.685073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:21.24659Z","time spent":"438.475371ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-24T19:37:21.685098Z","caller":"traceutil/trace.go:171","msg":"trace[1526935798] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:555; }","duration":"192.741885ms","start":"2026-08-24T19:37:21.492345Z","end":"2026-08-24T19:37:21.685086Z","steps":["trace[1526935798] 'agreement among raft nodes before linearized reading' (duration: 192.669752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:21.685113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.99373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:21.685131Z","caller":"traceutil/trace.go:171","msg":"trace[1556621841] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:555; }","duration":"192.022222ms","start":"2026-08-24T19:37:21.493103Z","end":"2026-08-24T19:37:21.685125Z","steps":["trace[1556621841] 'agreement among raft nodes before linearized reading' (duration: 192.000741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:21.684977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.578279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:21.685204Z","caller":"traceutil/trace.go:171","msg":"trace[252084521] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:555; }","duration":"192.809346ms","start":"2026-08-24T19:37:21.492381Z","end":"2026-08-24T19:37:21.685191Z","steps":["trace[252084521] 'agreement among raft nodes before linearized reading' (duration: 192.584389ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:21.684087Z","caller":"traceutil/trace.go:171","msg":"trace[1310620408] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"568.296169ms","start":"2026-08-24T19:37:21.115784Z","end":"2026-08-24T19:37:21.684081Z","steps":["trace[1310620408] 'process raft request' (duration: 567.923486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:21.68543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:21.115748Z","time spent":"569.59802ms","remote":"127.0.0.1:39212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T19:37:22.019954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.315489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T19:37:22.020077Z","caller":"traceutil/trace.go:171","msg":"trace[1393574967] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"282.413661ms","start":"2026-08-24T19:37:21.737644Z","end":"2026-08-24T19:37:22.020057Z","steps":["trace[1393574967] 'read index received' (duration: 56.538284ms)","trace[1393574967] 'applied index is now lower than readState.Index' (duration: 225.873977ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T19:37:22.020229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.996908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T19:37:22.020221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.539525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:22.020086Z","caller":"traceutil/trace.go:171","msg":"trace[1525079842] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"331.207891ms","start":"2026-08-24T19:37:21.688853Z","end":"2026-08-24T19:37:22.020061Z","steps":["trace[1525079842] 'process raft request' (duration: 105.339935ms)","trace[1525079842] 'compare' (duration: 225.126004ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:37:22.020272Z","caller":"traceutil/trace.go:171","msg":"trace[1698230622] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:556; }","duration":"230.061599ms","start":"2026-08-24T19:37:21.7902Z","end":"2026-08-24T19:37:22.020262Z","steps":["trace[1698230622] 'agreement among raft nodes before linearized reading' (duration: 229.999007ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:22.020285Z","caller":"traceutil/trace.go:171","msg":"trace[1701590329] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:556; }","duration":"282.668419ms","start":"2026-08-24T19:37:21.737604Z","end":"2026-08-24T19:37:22.020272Z","steps":["trace[1701590329] 'agreement among raft nodes before linearized reading' (duration: 282.536315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:22.020443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:21.688841Z","time spent":"331.441348ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T19:37:22.02062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.317856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:22.020671Z","caller":"traceutil/trace.go:171","msg":"trace[1047469873] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:556; }","duration":"230.371949ms","start":"2026-08-24T19:37:21.79029Z","end":"2026-08-24T19:37:22.020662Z","steps":["trace[1047469873] 'agreement among raft nodes before linearized reading' (duration: 230.283676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:22.020656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.845265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T19:37:22.020776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.639951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:22.020751Z","caller":"traceutil/trace.go:171","msg":"trace[2002714304] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:556; }","duration":"175.016501ms","start":"2026-08-24T19:37:21.845718Z","end":"2026-08-24T19:37:22.020734Z","steps":["trace[2002714304] 'agreement among raft nodes before linearized reading' (duration: 174.848346ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:22.020813Z","caller":"traceutil/trace.go:171","msg":"trace[1316531347] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:556; }","duration":"175.722653ms","start":"2026-08-24T19:37:21.84508Z","end":"2026-08-24T19:37:22.020803Z","steps":["trace[1316531347] 'agreement among raft nodes before linearized reading' (duration: 175.669712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:22.020969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.864258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T19:37:22.021006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.564693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:22.021037Z","caller":"traceutil/trace.go:171","msg":"trace[242299070] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:556; }","duration":"114.634115ms","start":"2026-08-24T19:37:21.906394Z","end":"2026-08-24T19:37:22.021028Z","steps":["trace[242299070] 'agreement among raft nodes before linearized reading' (duration: 114.582934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:22.021165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.276505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:22.021203Z","caller":"traceutil/trace.go:171","msg":"trace[708085471] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:556; }","duration":"114.316085ms","start":"2026-08-24T19:37:21.906879Z","end":"2026-08-24T19:37:22.021195Z","steps":["trace[708085471] 'agreement among raft nodes before linearized reading' (duration: 114.261863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:22.021214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.657461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:22.021285Z","caller":"traceutil/trace.go:171","msg":"trace[1188917219] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:556; }","duration":"142.743123ms","start":"2026-08-24T19:37:21.878527Z","end":"2026-08-24T19:37:22.02127Z","steps":["trace[1188917219] 'agreement among raft nodes before linearized reading' (duration: 142.66038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:22.021341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.716217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:22.02137Z","caller":"traceutil/trace.go:171","msg":"trace[1328071101] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:556; }","duration":"114.775099ms","start":"2026-08-24T19:37:21.906587Z","end":"2026-08-24T19:37:22.021362Z","steps":["trace[1328071101] 'agreement among raft nodes before linearized reading' (duration: 114.728258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:22.021124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.523396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:22.021508Z","caller":"traceutil/trace.go:171","msg":"trace[385528844] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:556; }","duration":"142.937479ms","start":"2026-08-24T19:37:21.87856Z","end":"2026-08-24T19:37:22.021498Z","steps":["trace[385528844] 'agreement among raft nodes before linearized reading' (duration: 142.519776ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:22.021006Z","caller":"traceutil/trace.go:171","msg":"trace[2075700996] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:556; }","duration":"175.905819ms","start":"2026-08-24T19:37:21.845092Z","end":"2026-08-24T19:37:22.020998Z","steps":["trace[2075700996] 'agreement among raft nodes before linearized reading' (duration: 175.849488ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:25.650308Z","caller":"traceutil/trace.go:171","msg":"trace[1747711725] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"568.872091ms","start":"2026-08-24T19:37:25.081411Z","end":"2026-08-24T19:37:25.650283Z","steps":["trace[1747711725] 'process raft request' (duration: 568.252172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:25.650465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:25.081387Z","time spent":"568.998136ms","remote":"127.0.0.1:39212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T19:37:25.883518Z","caller":"traceutil/trace.go:171","msg":"trace[124372704] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:594; }","duration":"456.838215ms","start":"2026-08-24T19:37:25.426643Z","end":"2026-08-24T19:37:25.883481Z","steps":["trace[124372704] 'read index received' (duration: 223.014031ms)","trace[124372704] 'applied index is now lower than readState.Index' (duration: 233.819444ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T19:37:25.883678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.016491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-24T19:37:25.883565Z","caller":"traceutil/trace.go:171","msg":"trace[1086132617] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"443.712339ms","start":"2026-08-24T19:37:25.439828Z","end":"2026-08-24T19:37:25.883541Z","steps":["trace[1086132617] 'process raft request' (duration: 443.465401ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:25.883718Z","caller":"traceutil/trace.go:171","msg":"trace[783184087] range","detail":"{range_begin:/registry/masterleases/199.19.213.74; range_end:; response_count:1; response_revision:577; }","duration":"457.103532ms","start":"2026-08-24T19:37:25.426604Z","end":"2026-08-24T19:37:25.883707Z","steps":["trace[783184087] 'agreement among raft nodes before linearized reading' (duration: 457.009889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:25.883749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:25.426585Z","time spent":"457.156374ms","remote":"127.0.0.1:39146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.74\" "} {"level":"warn","ts":"2026-08-24T19:37:25.8838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:25.439808Z","time spent":"443.933417ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T19:37:25.883577Z","caller":"traceutil/trace.go:171","msg":"trace[1717094615] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"163.729172ms","start":"2026-08-24T19:37:25.719831Z","end":"2026-08-24T19:37:25.88356Z","steps":["trace[1717094615] 'process raft request' (duration: 163.526785ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:25.883617Z","caller":"traceutil/trace.go:171","msg":"trace[1035055969] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"756.002324ms","start":"2026-08-24T19:37:25.127538Z","end":"2026-08-24T19:37:25.883541Z","steps":["trace[1035055969] 'process raft request' (duration: 723.169291ms)","trace[1035055969] 'compare' (duration: 32.445981ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T19:37:25.884104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.911103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:25.884136Z","caller":"traceutil/trace.go:171","msg":"trace[767223021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"393.996825ms","start":"2026-08-24T19:37:25.49013Z","end":"2026-08-24T19:37:25.884127Z","steps":["trace[767223021] 'agreement among raft nodes before linearized reading' (duration: 393.950434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:25.884169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:25.490104Z","time spent":"394.059328ms","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T19:37:25.884237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:25.127515Z","time spent":"756.617933ms","remote":"127.0.0.1:39190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T19:37:26.346387Z","caller":"traceutil/trace.go:171","msg":"trace[89097421] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"290.333308ms","start":"2026-08-24T19:37:26.056024Z","end":"2026-08-24T19:37:26.346358Z","steps":["trace[89097421] 'read index received' (duration: 31.462586ms)","trace[89097421] 'applied index is now lower than readState.Index' (duration: 258.868822ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:37:26.34658Z","caller":"traceutil/trace.go:171","msg":"trace[1916887002] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"291.662948ms","start":"2026-08-24T19:37:26.054902Z","end":"2026-08-24T19:37:26.346565Z","steps":["trace[1916887002] 'process raft request' (duration: 290.461292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:26.346823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.771871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T19:37:26.346934Z","caller":"traceutil/trace.go:171","msg":"trace[509205138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"290.898255ms","start":"2026-08-24T19:37:26.056018Z","end":"2026-08-24T19:37:26.346916Z","steps":["trace[509205138] 'agreement among raft nodes before linearized reading' (duration: 290.618527ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:26.449905Z","caller":"traceutil/trace.go:171","msg":"trace[440631545] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"103.323119ms","start":"2026-08-24T19:37:26.34653Z","end":"2026-08-24T19:37:26.449853Z","steps":["trace[440631545] 'read index received' (duration: 89.745343ms)","trace[440631545] 'applied index is now lower than readState.Index' (duration: 13.576786ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T19:37:26.450178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.826914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T19:37:26.451805Z","caller":"traceutil/trace.go:171","msg":"trace[1197057254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"294.581178ms","start":"2026-08-24T19:37:26.157208Z","end":"2026-08-24T19:37:26.451789Z","steps":["trace[1197057254] 'agreement among raft nodes before linearized reading' (duration: 292.753582ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:26.452115Z","caller":"traceutil/trace.go:171","msg":"trace[1567506773] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"117.917458ms","start":"2026-08-24T19:37:26.334154Z","end":"2026-08-24T19:37:26.452071Z","steps":["trace[1567506773] 'process raft request' (duration: 111.961984ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:37:26.459646Z","caller":"traceutil/trace.go:171","msg":"trace[1984226600] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"106.89782ms","start":"2026-08-24T19:37:26.352726Z","end":"2026-08-24T19:37:26.459624Z","steps":["trace[1984226600] 'process raft request' (duration: 106.786887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:26.994477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15134241223476806390,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T19:37:27.196471Z","caller":"traceutil/trace.go:171","msg":"trace[1454505685] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"736.238216ms","start":"2026-08-24T19:37:26.460209Z","end":"2026-08-24T19:37:27.196447Z","steps":["trace[1454505685] 'process raft request' (duration: 736.106092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:27.196615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:26.460193Z","time spent":"736.349969ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T19:37:27.205865Z","caller":"traceutil/trace.go:171","msg":"trace[1704405227] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"712.081063ms","start":"2026-08-24T19:37:26.493772Z","end":"2026-08-24T19:37:27.205853Z","steps":["trace[1704405227] 'read index received' (duration: 702.877472ms)","trace[1704405227] 'applied index is now lower than readState.Index' (duration: 9.202841ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:37:27.205971Z","caller":"traceutil/trace.go:171","msg":"trace[979720853] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"691.172682ms","start":"2026-08-24T19:37:26.514785Z","end":"2026-08-24T19:37:27.205958Z","steps":["trace[979720853] 'process raft request' (duration: 691.023486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:27.206071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:26.514763Z","time spent":"691.280975ms","remote":"127.0.0.1:39346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T19:37:27.206056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.280349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:37:27.206185Z","caller":"traceutil/trace.go:171","msg":"trace[1078827506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"712.441114ms","start":"2026-08-24T19:37:26.493731Z","end":"2026-08-24T19:37:27.206172Z","steps":["trace[1078827506] 'agreement among raft nodes before linearized reading' (duration: 712.169855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:27.206806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:26.493709Z","time spent":"713.063793ms","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T19:37:42.979083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.868219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T19:37:42.979162Z","caller":"traceutil/trace.go:171","msg":"trace[1286859002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"386.984572ms","start":"2026-08-24T19:37:42.59216Z","end":"2026-08-24T19:37:42.979145Z","steps":["trace[1286859002] 'range keys from in-memory index tree' (duration: 386.653983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:37:42.979201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:37:42.592145Z","time spent":"387.048124ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-24T19:38:01.29154Z","caller":"traceutil/trace.go:171","msg":"trace[1598454781] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"144.809441ms","start":"2026-08-24T19:38:01.146707Z","end":"2026-08-24T19:38:01.291516Z","steps":["trace[1598454781] 'read index received' (duration: 144.614605ms)","trace[1598454781] 'applied index is now lower than readState.Index' (duration: 190.925µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:38:01.291615Z","caller":"traceutil/trace.go:171","msg":"trace[383807139] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"160.798429ms","start":"2026-08-24T19:38:01.130779Z","end":"2026-08-24T19:38:01.291577Z","steps":["trace[383807139] 'process raft request' (duration: 160.527521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:38:01.291739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.009716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T19:38:01.291791Z","caller":"traceutil/trace.go:171","msg":"trace[2082688329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"145.075397ms","start":"2026-08-24T19:38:01.146701Z","end":"2026-08-24T19:38:01.291777Z","steps":["trace[2082688329] 'agreement among raft nodes before linearized reading' (duration: 144.904653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:38:01.292059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.755451ms","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-08-24T19:38:01.292123Z","caller":"traceutil/trace.go:171","msg":"trace[1952552195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1077; }","duration":"141.871785ms","start":"2026-08-24T19:38:01.150239Z","end":"2026-08-24T19:38:01.29211Z","steps":["trace[1952552195] 'agreement among raft nodes before linearized reading' (duration: 141.636828ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:38:30.428981Z","caller":"traceutil/trace.go:171","msg":"trace[710367836] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"141.599643ms","start":"2026-08-24T19:38:30.287352Z","end":"2026-08-24T19:38:30.428951Z","steps":["trace[710367836] 'process raft request' (duration: 71.030773ms)","trace[710367836] 'compare' (duration: 70.09502ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T19:38:39.632267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.972712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:38:39.632331Z","caller":"traceutil/trace.go:171","msg":"trace[468694495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1431; }","duration":"143.079563ms","start":"2026-08-24T19:38:39.489236Z","end":"2026-08-24T19:38:39.632316Z","steps":["trace[468694495] 'range keys from in-memory index tree' (duration: 142.848849ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:38:47.793167Z","caller":"traceutil/trace.go:171","msg":"trace[1262396555] linearizableReadLoop","detail":"{readStateIndex:1599; appliedIndex:1598; }","duration":"122.144437ms","start":"2026-08-24T19:38:47.670994Z","end":"2026-08-24T19:38:47.793139Z","steps":["trace[1262396555] 'read index received' (duration: 121.972562ms)","trace[1262396555] 'applied index is now lower than readState.Index' (duration: 170.675µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T19:38:47.793558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.544356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-24T19:38:47.7936Z","caller":"traceutil/trace.go:171","msg":"trace[2114378336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1538; }","duration":"122.608837ms","start":"2026-08-24T19:38:47.670976Z","end":"2026-08-24T19:38:47.793585Z","steps":["trace[2114378336] 'agreement among raft nodes before linearized reading' (duration: 122.464984ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:38:47.793945Z","caller":"traceutil/trace.go:171","msg":"trace[2130776820] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"123.999328ms","start":"2026-08-24T19:38:47.669914Z","end":"2026-08-24T19:38:47.793913Z","steps":["trace[2130776820] 'process raft request' (duration: 123.096708ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:39:05.002348Z","caller":"traceutil/trace.go:171","msg":"trace[131982493] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"440.602826ms","start":"2026-08-24T19:39:04.561725Z","end":"2026-08-24T19:39:05.002328Z","steps":["trace[131982493] 'read index received' (duration: 440.397651ms)","trace[131982493] 'applied index is now lower than readState.Index' (duration: 204.055µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:39:05.002419Z","caller":"traceutil/trace.go:171","msg":"trace[407803068] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"469.470626ms","start":"2026-08-24T19:39:04.532907Z","end":"2026-08-24T19:39:05.002377Z","steps":["trace[407803068] 'process raft request' (duration: 469.268951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:39:05.002604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.854622ms","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-08-24T19:39:05.002684Z","caller":"traceutil/trace.go:171","msg":"trace[1429931069] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1843; }","duration":"440.979304ms","start":"2026-08-24T19:39:04.561685Z","end":"2026-08-24T19:39:05.002665Z","steps":["trace[1429931069] 'agreement among raft nodes before linearized reading' (duration: 440.77523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:39:05.002708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.080196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-08-24T19:39:05.002729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:39:04.561658Z","time spent":"441.062645ms","remote":"127.0.0.1:42434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-24T19:39:05.002761Z","caller":"traceutil/trace.go:171","msg":"trace[1465498173] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1843; }","duration":"372.183727ms","start":"2026-08-24T19:39:04.630562Z","end":"2026-08-24T19:39:05.002745Z","steps":["trace[1465498173] 'agreement among raft nodes before linearized reading' (duration: 372.014803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:39:05.0028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:39:04.630542Z","time spent":"372.25058ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-24T19:39:05.002891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.777185ms","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-08-24T19:39:05.002959Z","caller":"traceutil/trace.go:171","msg":"trace[1894702949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1843; }","duration":"369.863276ms","start":"2026-08-24T19:39:04.63308Z","end":"2026-08-24T19:39:05.002943Z","steps":["trace[1894702949] 'agreement among raft nodes before linearized reading' (duration: 369.696883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:39:05.002996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:39:04.633069Z","time spent":"369.919327ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T19:39:05.00302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.38212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T19:39:05.00305Z","caller":"traceutil/trace.go:171","msg":"trace[1038033907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1843; }","duration":"371.488042ms","start":"2026-08-24T19:39:04.631553Z","end":"2026-08-24T19:39:05.003041Z","steps":["trace[1038033907] 'agreement among raft nodes before linearized reading' (duration: 371.402721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:39:05.003075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:39:04.631535Z","time spent":"371.535074ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T19:39:05.003502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:39:04.532875Z","time spent":"469.617969ms","remote":"127.0.0.1:39310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T19:39:39.366968Z","caller":"traceutil/trace.go:171","msg":"trace[1288320772] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"101.34129ms","start":"2026-08-24T19:39:39.265603Z","end":"2026-08-24T19:39:39.366944Z","steps":["trace[1288320772] 'process raft request' (duration: 101.132995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:39:39.36745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.275156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-24T19:39:39.367504Z","caller":"traceutil/trace.go:171","msg":"trace[1987087880] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2080; }","duration":"100.365479ms","start":"2026-08-24T19:39:39.267122Z","end":"2026-08-24T19:39:39.367488Z","steps":["trace[1987087880] 'agreement among raft nodes before linearized reading' (duration: 100.098654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:39:39.815365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.479223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:39:39.815578Z","caller":"traceutil/trace.go:171","msg":"trace[300198836] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2082; }","duration":"234.716829ms","start":"2026-08-24T19:39:39.580841Z","end":"2026-08-24T19:39:39.815558Z","steps":["trace[300198836] 'count revisions from in-memory index tree' (duration: 234.362091ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:39:40.181624Z","caller":"traceutil/trace.go:171","msg":"trace[898360458] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"103.123704ms","start":"2026-08-24T19:39:40.078465Z","end":"2026-08-24T19:39:40.181589Z","steps":["trace[898360458] 'process raft request' (duration: 102.876439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:39:40.578431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.121997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T19:39:40.578548Z","caller":"traceutil/trace.go:171","msg":"trace[483672361] linearizableReadLoop","detail":"{readStateIndex:2164; appliedIndex:2163; }","duration":"397.014422ms","start":"2026-08-24T19:39:40.181518Z","end":"2026-08-24T19:39:40.578533Z","steps":["trace[483672361] 'read index received' (duration: 207.703781ms)","trace[483672361] 'applied index is now lower than readState.Index' (duration: 189.309421ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:39:40.579032Z","caller":"traceutil/trace.go:171","msg":"trace[528345767] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"401.409525ms","start":"2026-08-24T19:39:40.177581Z","end":"2026-08-24T19:39:40.578991Z","steps":["trace[528345767] 'process raft request' (duration: 211.622493ms)","trace[528345767] 'compare' (duration: 188.950794ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T19:39:40.579129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.592035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T19:39:40.579199Z","caller":"traceutil/trace.go:171","msg":"trace[654609638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2086; }","duration":"447.672716ms","start":"2026-08-24T19:39:40.13151Z","end":"2026-08-24T19:39:40.579183Z","steps":["trace[654609638] 'agreement among raft nodes before linearized reading' (duration: 447.465611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:39:40.579234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:39:40.17755Z","time spent":"401.590849ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T19:39:40.579288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:39:40.131437Z","time spent":"447.807868ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T19:39:40.919103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.646988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T19:39:40.919708Z","caller":"traceutil/trace.go:171","msg":"trace[1779751952] linearizableReadLoop","detail":"{readStateIndex:2165; appliedIndex:2164; }","duration":"341.118998ms","start":"2026-08-24T19:39:40.578573Z","end":"2026-08-24T19:39:40.919692Z","steps":["trace[1779751952] 'read index received' (duration: 109.833178ms)","trace[1779751952] 'applied index is now lower than readState.Index' (duration: 231.28457ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:39:40.919726Z","caller":"traceutil/trace.go:171","msg":"trace[71565666] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"733.730137ms","start":"2026-08-24T19:39:40.185963Z","end":"2026-08-24T19:39:40.919693Z","steps":["trace[71565666] 'process raft request' (duration: 502.399145ms)","trace[71565666] 'compare' (duration: 230.311631ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T19:39:40.919908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:39:40.185941Z","time spent":"733.88941ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T19:39:41.082564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.294458ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-24T19:39:41.082893Z","caller":"traceutil/trace.go:171","msg":"trace[194420431] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"499.023601ms","start":"2026-08-24T19:39:40.583849Z","end":"2026-08-24T19:39:41.082872Z","steps":["trace[194420431] 'process raft request' (duration: 498.820968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:39:41.082949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.440098ms","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-08-24T19:39:41.083037Z","caller":"traceutil/trace.go:171","msg":"trace[1592527017] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2087; }","duration":"707.575201ms","start":"2026-08-24T19:39:40.375435Z","end":"2026-08-24T19:39:41.083011Z","steps":["trace[1592527017] 'agreement among raft nodes before linearized reading' (duration: 544.871725ms)","trace[1592527017] 'range keys from in-memory index tree' (duration: 162.378839ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T19:39:41.083034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:39:40.583827Z","time spent":"499.125434ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T19:39:41.083105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:39:40.375414Z","time spent":"707.681333ms","remote":"127.0.0.1:42434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-24T19:39:41.083088Z","caller":"traceutil/trace.go:171","msg":"trace[288584380] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"438.363886ms","start":"2026-08-24T19:39:40.644698Z","end":"2026-08-24T19:39:41.083062Z","steps":["trace[288584380] 'process raft request' (duration: 438.124071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:39:41.083227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:39:40.644673Z","time spent":"438.47762ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T19:41:19.874693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.789617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T19:41:19.874838Z","caller":"traceutil/trace.go:171","msg":"trace[1208376607] linearizableReadLoop","detail":"{readStateIndex:2827; appliedIndex:2826; }","duration":"119.822581ms","start":"2026-08-24T19:41:19.754993Z","end":"2026-08-24T19:41:19.874815Z","steps":["trace[1208376607] 'read index received' (duration: 41.752µs)","trace[1208376607] 'applied index is now lower than readState.Index' (duration: 119.779399ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:41:19.874961Z","caller":"traceutil/trace.go:171","msg":"trace[1306629050] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"317.853833ms","start":"2026-08-24T19:41:19.55708Z","end":"2026-08-24T19:41:19.874934Z","steps":["trace[1306629050] 'process raft request' (duration: 123.487039ms)","trace[1306629050] 'compare' (duration: 193.6181ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T19:41:19.875114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T19:41:19.557063Z","time spent":"317.972859ms","remote":"127.0.0.1:39370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T19:41:19.875548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.556283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ced525a3e297ce\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-08-24T19:41:19.875602Z","caller":"traceutil/trace.go:171","msg":"trace[377625247] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ced525a3e297ce; range_end:; response_count:1; response_revision:2726; }","duration":"120.653869ms","start":"2026-08-24T19:41:19.754935Z","end":"2026-08-24T19:41:19.875589Z","steps":["trace[377625247] 'agreement among raft nodes before linearized reading' (duration: 120.00943ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:41:20.015687Z","caller":"traceutil/trace.go:171","msg":"trace[1756399181] transaction","detail":"{read_only:false; response_revision:2727; number_of_response:1; }","duration":"137.854569ms","start":"2026-08-24T19:41:19.877795Z","end":"2026-08-24T19:41:20.01565Z","steps":["trace[1756399181] 'process raft request' (duration: 135.640978ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:41:20.029752Z","caller":"traceutil/trace.go:171","msg":"trace[1096153513] linearizableReadLoop","detail":"{readStateIndex:2829; appliedIndex:2827; }","duration":"115.411402ms","start":"2026-08-24T19:41:19.914308Z","end":"2026-08-24T19:41:20.02972Z","steps":["trace[1096153513] 'read index received' (duration: 99.141796ms)","trace[1096153513] 'applied index is now lower than readState.Index' (duration: 16.268376ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:41:20.030119Z","caller":"traceutil/trace.go:171","msg":"trace[861338866] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"151.18448ms","start":"2026-08-24T19:41:19.878908Z","end":"2026-08-24T19:41:20.030092Z","steps":["trace[861338866] 'process raft request' (duration: 150.625844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:41:20.030248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.887274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-24T19:41:20.030296Z","caller":"traceutil/trace.go:171","msg":"trace[1623994728] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2728; }","duration":"116.01695ms","start":"2026-08-24T19:41:19.914268Z","end":"2026-08-24T19:41:20.030285Z","steps":["trace[1623994728] 'agreement among raft nodes before linearized reading' (duration: 115.81443ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:44:56.079308Z","caller":"traceutil/trace.go:171","msg":"trace[497528815] transaction","detail":"{read_only:false; response_revision:4335; number_of_response:1; }","duration":"167.341275ms","start":"2026-08-24T19:44:55.911933Z","end":"2026-08-24T19:44:56.079274Z","steps":["trace[497528815] 'process raft request' (duration: 67.620033ms)","trace[497528815] 'compare' (duration: 99.462454ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:44:56.079558Z","caller":"traceutil/trace.go:171","msg":"trace[363193025] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4336; }","duration":"160.066656ms","start":"2026-08-24T19:44:55.919484Z","end":"2026-08-24T19:44:56.079551Z","steps":["trace[363193025] 'process raft request' (duration: 159.871771ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:44:56.079735Z","caller":"traceutil/trace.go:171","msg":"trace[2063371835] transaction","detail":"{read_only:false; response_revision:4336; number_of_response:1; }","duration":"160.292052ms","start":"2026-08-24T19:44:55.919437Z","end":"2026-08-24T19:44:56.079729Z","steps":["trace[2063371835] 'process raft request' (duration: 159.802589ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:44:56.079915Z","caller":"traceutil/trace.go:171","msg":"trace[668674062] linearizableReadLoop","detail":"{readStateIndex:4507; appliedIndex:4503; }","duration":"148.292323ms","start":"2026-08-24T19:44:55.931613Z","end":"2026-08-24T19:44:56.079905Z","steps":["trace[668674062] 'read index received' (duration: 47.946124ms)","trace[668674062] 'applied index is now lower than readState.Index' (duration: 100.345239ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:44:56.079898Z","caller":"traceutil/trace.go:171","msg":"trace[1325234173] transaction","detail":"{read_only:false; response_revision:4337; number_of_response:1; }","duration":"151.643215ms","start":"2026-08-24T19:44:55.928233Z","end":"2026-08-24T19:44:56.079876Z","steps":["trace[1325234173] 'process raft request' (duration: 151.198653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:44:56.079991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.364554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/grafana-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:44:56.080017Z","caller":"traceutil/trace.go:171","msg":"trace[1600070207] range","detail":"{range_begin:/registry/secrets/monitoring/grafana-tls; range_end:; response_count:0; response_revision:4343; }","duration":"148.428306ms","start":"2026-08-24T19:44:55.931583Z","end":"2026-08-24T19:44:56.080011Z","steps":["trace[1600070207] 'agreement among raft nodes before linearized reading' (duration: 148.360104ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:44:56.0801Z","caller":"traceutil/trace.go:171","msg":"trace[931378847] transaction","detail":"{read_only:false; response_revision:4338; number_of_response:1; }","duration":"148.341864ms","start":"2026-08-24T19:44:55.931748Z","end":"2026-08-24T19:44:56.08009Z","steps":["trace[931378847] 'process raft request' (duration: 147.731767ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:44:56.080135Z","caller":"traceutil/trace.go:171","msg":"trace[444429302] transaction","detail":"{read_only:false; response_revision:4341; number_of_response:1; }","duration":"137.344942ms","start":"2026-08-24T19:44:55.942786Z","end":"2026-08-24T19:44:56.080131Z","steps":["trace[444429302] 'process raft request' (duration: 136.842558ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:44:56.080236Z","caller":"traceutil/trace.go:171","msg":"trace[554958258] transaction","detail":"{read_only:false; response_revision:4339; number_of_response:1; }","duration":"148.098738ms","start":"2026-08-24T19:44:55.932128Z","end":"2026-08-24T19:44:56.080227Z","steps":["trace[554958258] 'process raft request' (duration: 147.44341ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:44:56.080287Z","caller":"traceutil/trace.go:171","msg":"trace[1042016347] transaction","detail":"{read_only:false; response_revision:4342; number_of_response:1; }","duration":"130.662499ms","start":"2026-08-24T19:44:55.949615Z","end":"2026-08-24T19:44:56.080278Z","steps":["trace[1042016347] 'process raft request' (duration: 130.217777ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:44:56.080347Z","caller":"traceutil/trace.go:171","msg":"trace[182027250] transaction","detail":"{read_only:false; response_revision:4340; number_of_response:1; }","duration":"144.760026ms","start":"2026-08-24T19:44:55.935578Z","end":"2026-08-24T19:44:56.080338Z","steps":["trace[182027250] 'process raft request' (duration: 144.027276ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:44:56.080359Z","caller":"traceutil/trace.go:171","msg":"trace[1626309362] transaction","detail":"{read_only:false; response_revision:4343; number_of_response:1; }","duration":"127.353999ms","start":"2026-08-24T19:44:55.953Z","end":"2026-08-24T19:44:56.080354Z","steps":["trace[1626309362] 'process raft request' (duration: 126.873776ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:44:58.503857Z","caller":"traceutil/trace.go:171","msg":"trace[179639850] transaction","detail":"{read_only:false; response_revision:4416; number_of_response:1; }","duration":"102.561802ms","start":"2026-08-24T19:44:58.401275Z","end":"2026-08-24T19:44:58.503837Z","steps":["trace[179639850] 'process raft request' (duration: 102.510831ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:44:58.503891Z","caller":"traceutil/trace.go:171","msg":"trace[1696386863] transaction","detail":"{read_only:false; response_revision:4415; number_of_response:1; }","duration":"116.277948ms","start":"2026-08-24T19:44:58.387597Z","end":"2026-08-24T19:44:58.503875Z","steps":["trace[1696386863] 'process raft request' (duration: 83.603094ms)","trace[1696386863] 'compare' (duration: 32.471338ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:45:03.819801Z","caller":"traceutil/trace.go:171","msg":"trace[705907563] linearizableReadLoop","detail":"{readStateIndex:4732; appliedIndex:4731; }","duration":"108.025644ms","start":"2026-08-24T19:45:03.711751Z","end":"2026-08-24T19:45:03.819776Z","steps":["trace[705907563] 'read index received' (duration: 107.87999ms)","trace[705907563] 'applied index is now lower than readState.Index' (duration: 141.654µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:45:03.819952Z","caller":"traceutil/trace.go:171","msg":"trace[1430147472] transaction","detail":"{read_only:false; response_revision:4559; number_of_response:1; }","duration":"116.939477ms","start":"2026-08-24T19:45:03.702995Z","end":"2026-08-24T19:45:03.819935Z","steps":["trace[1430147472] 'process raft request' (duration: 116.609318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:45:03.820172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.416425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-24T19:45:03.820222Z","caller":"traceutil/trace.go:171","msg":"trace[1523835090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4559; }","duration":"108.516267ms","start":"2026-08-24T19:45:03.711698Z","end":"2026-08-24T19:45:03.820214Z","steps":["trace[1523835090] 'agreement among raft nodes before linearized reading' (duration: 108.373083ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:45:06.533972Z","caller":"traceutil/trace.go:171","msg":"trace[261952589] transaction","detail":"{read_only:false; response_revision:4617; number_of_response:1; }","duration":"222.607627ms","start":"2026-08-24T19:45:06.311346Z","end":"2026-08-24T19:45:06.533954Z","steps":["trace[261952589] 'process raft request' (duration: 222.218196ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:45:12.10608Z","caller":"traceutil/trace.go:171","msg":"trace[523173848] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"226.736651ms","start":"2026-08-24T19:45:11.879305Z","end":"2026-08-24T19:45:12.106042Z","steps":["trace[523173848] 'process raft request' (duration: 226.415532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:45:12.420968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.446971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b629d03-336b-4614-81a3-4c3b09ac73f2\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-08-24T19:45:12.421032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.26531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5c476d544-vpqj5.18ced5603165d367\" ","response":"range_response_count:1 size:893"} {"level":"info","ts":"2026-08-24T19:45:12.421062Z","caller":"traceutil/trace.go:171","msg":"trace[1928950599] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2b629d03-336b-4614-81a3-4c3b09ac73f2; range_end:; response_count:1; response_revision:4712; }","duration":"174.594186ms","start":"2026-08-24T19:45:12.246445Z","end":"2026-08-24T19:45:12.421039Z","steps":["trace[1928950599] 'range keys from in-memory index tree' (duration: 174.197036ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:45:12.421108Z","caller":"traceutil/trace.go:171","msg":"trace[1978030402] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5c476d544-vpqj5.18ced5603165d367; range_end:; response_count:1; response_revision:4712; }","duration":"173.393944ms","start":"2026-08-24T19:45:12.247695Z","end":"2026-08-24T19:45:12.421089Z","steps":["trace[1978030402] 'range keys from in-memory index tree' (duration: 173.085745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:45:12.937679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.370536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-24T19:45:12.937834Z","caller":"traceutil/trace.go:171","msg":"trace[1448038706] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4719; }","duration":"111.565941ms","start":"2026-08-24T19:45:12.826245Z","end":"2026-08-24T19:45:12.937811Z","steps":["trace[1448038706] 'range keys from in-memory index tree' (duration: 111.16885ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:45:13.401995Z","caller":"traceutil/trace.go:171","msg":"trace[1453512767] linearizableReadLoop","detail":"{readStateIndex:4904; appliedIndex:4903; }","duration":"120.774815ms","start":"2026-08-24T19:45:13.281168Z","end":"2026-08-24T19:45:13.401943Z","steps":["trace[1453512767] 'read index received' (duration: 109.579014ms)","trace[1453512767] 'applied index is now lower than readState.Index' (duration: 11.193061ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:45:13.402058Z","caller":"traceutil/trace.go:171","msg":"trace[1001259216] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"139.397985ms","start":"2026-08-24T19:45:13.262623Z","end":"2026-08-24T19:45:13.402021Z","steps":["trace[1001259216] 'process raft request' (duration: 128.168173ms)","trace[1001259216] 'compare' (duration: 10.694447ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:45:13.402761Z","caller":"traceutil/trace.go:171","msg":"trace[334285595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4725; }","duration":"100.020746ms","start":"2026-08-24T19:45:13.302662Z","end":"2026-08-24T19:45:13.402682Z","steps":["trace[334285595] 'agreement among raft nodes before linearized reading' (duration: 99.875803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:45:13.402269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.079883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-24T19:45:13.40344Z","caller":"traceutil/trace.go:171","msg":"trace[1425469315] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4725; }","duration":"121.72656ms","start":"2026-08-24T19:45:13.281149Z","end":"2026-08-24T19:45:13.402876Z","steps":["trace[1425469315] 'agreement among raft nodes before linearized reading' (duration: 120.98919ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:45:20.771495Z","caller":"traceutil/trace.go:171","msg":"trace[2130611419] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"170.805026ms","start":"2026-08-24T19:45:20.600657Z","end":"2026-08-24T19:45:20.771462Z","steps":["trace[2130611419] 'process raft request' (duration: 170.613321ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:45:25.282804Z","caller":"traceutil/trace.go:171","msg":"trace[328254726] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"169.615532ms","start":"2026-08-24T19:45:25.113164Z","end":"2026-08-24T19:45:25.28278Z","steps":["trace[328254726] 'process raft request' (duration: 169.450428ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:45:25.338366Z","caller":"traceutil/trace.go:171","msg":"trace[1381135616] linearizableReadLoop","detail":"{readStateIndex:5061; appliedIndex:5059; }","duration":"207.068695ms","start":"2026-08-24T19:45:25.131272Z","end":"2026-08-24T19:45:25.338341Z","steps":["trace[1381135616] 'read index received' (duration: 151.395898ms)","trace[1381135616] 'applied index is now lower than readState.Index' (duration: 55.671847ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:45:25.338495Z","caller":"traceutil/trace.go:171","msg":"trace[1978813276] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"213.465436ms","start":"2026-08-24T19:45:25.125008Z","end":"2026-08-24T19:45:25.338473Z","steps":["trace[1978813276] 'process raft request' (duration: 213.197609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:45:25.338708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.733023ms","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-08-24T19:45:25.338809Z","caller":"traceutil/trace.go:171","msg":"trace[1951823848] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4877; }","duration":"149.905309ms","start":"2026-08-24T19:45:25.188872Z","end":"2026-08-24T19:45:25.338777Z","steps":["trace[1951823848] 'agreement among raft nodes before linearized reading' (duration: 149.766625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:45:25.338727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.435745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T19:45:25.33901Z","caller":"traceutil/trace.go:171","msg":"trace[827426812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4877; }","duration":"207.704693ms","start":"2026-08-24T19:45:25.131234Z","end":"2026-08-24T19:45:25.338939Z","steps":["trace[827426812] 'agreement among raft nodes before linearized reading' (duration: 207.374183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:45:25.593794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.812811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T19:45:25.594122Z","caller":"traceutil/trace.go:171","msg":"trace[1235390672] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"251.253798ms","start":"2026-08-24T19:45:25.342856Z","end":"2026-08-24T19:45:25.594109Z","steps":["trace[1235390672] 'process raft request' (duration: 251.151525ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:45:25.594148Z","caller":"traceutil/trace.go:171","msg":"trace[1840155444] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"251.929875ms","start":"2026-08-24T19:45:25.342177Z","end":"2026-08-24T19:45:25.594107Z","steps":["trace[1840155444] 'process raft request' (duration: 91.707863ms)","trace[1840155444] 'compare' (duration: 159.597275ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T19:45:25.594118Z","caller":"traceutil/trace.go:171","msg":"trace[471625101] linearizableReadLoop","detail":"{readStateIndex:5063; appliedIndex:5061; }","duration":"244.555631ms","start":"2026-08-24T19:45:25.349541Z","end":"2026-08-24T19:45:25.594097Z","steps":["trace[471625101] 'read index received' (duration: 84.38043ms)","trace[471625101] 'applied index is now lower than readState.Index' (duration: 160.174001ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T19:45:25.594433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.879279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-08-24T19:45:25.594533Z","caller":"traceutil/trace.go:171","msg":"trace[1517817671] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4879; }","duration":"245.000582ms","start":"2026-08-24T19:45:25.349517Z","end":"2026-08-24T19:45:25.594518Z","steps":["trace[1517817671] 'agreement among raft nodes before linearized reading' (duration: 244.693144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:45:25.594607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.008537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T19:45:25.594654Z","caller":"traceutil/trace.go:171","msg":"trace[456315670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4879; }","duration":"105.087539ms","start":"2026-08-24T19:45:25.489558Z","end":"2026-08-24T19:45:25.594646Z","steps":["trace[456315670] 'agreement among raft nodes before linearized reading' (duration: 105.017837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:45:25.594631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.331274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-08-24T19:45:25.594685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.506717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-24T19:45:25.594752Z","caller":"traceutil/trace.go:171","msg":"trace[1057789774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4879; }","duration":"129.6223ms","start":"2026-08-24T19:45:25.465115Z","end":"2026-08-24T19:45:25.594737Z","steps":["trace[1057789774] 'agreement among raft nodes before linearized reading' (duration: 129.443335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:45:25.5948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.955954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.74\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-08-24T19:45:25.5948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.908942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-24T19:45:25.594871Z","caller":"traceutil/trace.go:171","msg":"trace[692389337] range","detail":"{range_begin:/registry/masterleases/199.19.213.74; range_end:; response_count:1; response_revision:4879; }","duration":"140.049007ms","start":"2026-08-24T19:45:25.454807Z","end":"2026-08-24T19:45:25.594856Z","steps":["trace[692389337] 'agreement among raft nodes before linearized reading' (duration: 139.921014ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:45:25.594703Z","caller":"traceutil/trace.go:171","msg":"trace[1875342220] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4879; }","duration":"198.453766ms","start":"2026-08-24T19:45:25.396232Z","end":"2026-08-24T19:45:25.594686Z","steps":["trace[1875342220] 'agreement among raft nodes before linearized reading' (duration: 198.22222ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:45:25.594892Z","caller":"traceutil/trace.go:171","msg":"trace[826301390] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4879; }","duration":"123.050396ms","start":"2026-08-24T19:45:25.471818Z","end":"2026-08-24T19:45:25.594868Z","steps":["trace[826301390] 'agreement among raft nodes before linearized reading' (duration: 122.709327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:45:25.911818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.761617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-24T19:45:25.911912Z","caller":"traceutil/trace.go:171","msg":"trace[477502682] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:4887; }","duration":"215.91071ms","start":"2026-08-24T19:45:25.695984Z","end":"2026-08-24T19:45:25.911894Z","steps":["trace[477502682] 'range keys from in-memory index tree' (duration: 215.635042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T19:45:25.911841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.738687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5c476d544-vpqj5\" ","response":"range_response_count:1 size:11839"} {"level":"info","ts":"2026-08-24T19:45:25.912162Z","caller":"traceutil/trace.go:171","msg":"trace[1950370669] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-5c476d544-vpqj5; range_end:; response_count:1; response_revision:4887; }","duration":"204.086726ms","start":"2026-08-24T19:45:25.708024Z","end":"2026-08-24T19:45:25.912111Z","steps":["trace[1950370669] 'range keys from in-memory index tree' (duration: 203.516391ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:45:26.102981Z","caller":"traceutil/trace.go:171","msg":"trace[1833939792] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"175.317741ms","start":"2026-08-24T19:45:25.927635Z","end":"2026-08-24T19:45:26.102953Z","steps":["trace[1833939792] 'process raft request' (duration: 175.127915ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T19:46:42.583842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2849} {"level":"info","ts":"2026-08-24T19:46:42.691712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2849,"took":"106.329892ms","hash":309482128,"current-db-size-bytes":53485568,"current-db-size":"54 MB","current-db-size-in-use-bytes":29118464,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-24T19:46:42.691792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":309482128,"revision":2849,"compact-revision":-1} {"level":"info","ts":"2026-08-24T19:51:42.590826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5513} {"level":"info","ts":"2026-08-24T19:51:42.693722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5513,"took":"100.851515ms","hash":2802574072,"current-db-size-bytes":53485568,"current-db-size":"54 MB","current-db-size-in-use-bytes":18239488,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-24T19:51:42.693811Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2802574072,"revision":5513,"compact-revision":2849}