{"level":"warn","ts":"2026-08-13T00:47:28.833416Z","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-13T00:47:28.834651Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-13T00:47:28.834737Z","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-13T00:47:28.834962Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-08-13T00:47:28.834984Z","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-13T00:47:28.841125Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-08-13T00:47:28.842558Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-13T00:47:28.848993Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.462451ms"} {"level":"info","ts":"2026-08-13T00:47:28.856404Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-08-13T00:47:28.856483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T00:47:28.85651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-08-13T00:47:28.856523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T00:47:28.856559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-08-13T00:47:28.856602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-08-13T00:47:28.863016Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T00:47:28.864526Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T00:47:28.865798Z","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-13T00:47:28.86804Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T00:47:28.868181Z","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-13T00:47:28.868265Z","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-13T00:47:28.868279Z","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-13T00:47:28.868636Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T00:47:28.86924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-08-13T00:47:28.869333Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-08-13T00:47:28.873286Z","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-13T00:47:28.873527Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T00:47:28.873565Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T00:47:28.873641Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-08-13T00:47:28.873657Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-08-13T00:47:29.059743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T00:47:29.059825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T00:47:29.059859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-08-13T00:47:29.059971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-08-13T00:47:29.059982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-08-13T00:47:29.059996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-08-13T00:47:29.060007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-08-13T00:47:29.066061Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:47:29.073257Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T00:47:29.073721Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T00:47:29.073899Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T00:47:29.075842Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-13T00:47:29.0762Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T00:47:29.076312Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T00:47:29.076448Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:47:29.076563Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:47:29.076585Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:47:29.078434Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-08-13T00:47:30.83416Z","caller":"traceutil/trace.go:171","msg":"trace[1526676051] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"133.559572ms","start":"2026-08-13T00:47:30.700545Z","end":"2026-08-13T00:47:30.834105Z","steps":["trace[1526676051] 'process raft request' (duration: 129.704222ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:47:30.834318Z","caller":"traceutil/trace.go:171","msg":"trace[1216122301] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"132.396931ms","start":"2026-08-13T00:47:30.701884Z","end":"2026-08-13T00:47:30.834281Z","steps":["trace[1216122301] 'process raft request' (duration: 132.119559ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:47:33.364302Z","caller":"traceutil/trace.go:171","msg":"trace[107896739] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"213.03618ms","start":"2026-08-13T00:47:33.151227Z","end":"2026-08-13T00:47:33.364263Z","steps":["trace[107896739] 'process raft request' (duration: 212.834561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:39.019619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153701238623197955,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-13T00:47:39.147986Z","caller":"traceutil/trace.go:171","msg":"trace[87425579] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"628.57396ms","start":"2026-08-13T00:47:38.519389Z","end":"2026-08-13T00:47:39.147963Z","steps":["trace[87425579] 'read index received' (duration: 628.395212ms)","trace[87425579] 'applied index is now lower than readState.Index' (duration: 177.778µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:47:39.148302Z","caller":"traceutil/trace.go:171","msg":"trace[463841930] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"674.392596ms","start":"2026-08-13T00:47:38.473899Z","end":"2026-08-13T00:47:39.148291Z","steps":["trace[463841930] 'process raft request' (duration: 673.945746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:39.1488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:38.473889Z","time spent":"674.448658ms","remote":"127.0.0.1:52768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:47:39.149012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.628397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-13T00:47:39.149058Z","caller":"traceutil/trace.go:171","msg":"trace[252185561] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:322; }","duration":"629.710581ms","start":"2026-08-13T00:47:38.519337Z","end":"2026-08-13T00:47:39.149047Z","steps":["trace[252185561] 'agreement among raft nodes before linearized reading' (duration: 629.637417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:39.149093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:38.519315Z","time spent":"629.771633ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-08-13T00:47:39.149252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.225155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-13T00:47:39.149285Z","caller":"traceutil/trace.go:171","msg":"trace[863712340] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:322; }","duration":"581.292829ms","start":"2026-08-13T00:47:38.567985Z","end":"2026-08-13T00:47:39.149278Z","steps":["trace[863712340] 'agreement among raft nodes before linearized reading' (duration: 581.239617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:39.149316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:38.567964Z","time spent":"581.34125ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-08-13T00:47:39.149559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.844259ms","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-13T00:47:39.149594Z","caller":"traceutil/trace.go:171","msg":"trace[1862924003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:322; }","duration":"393.939603ms","start":"2026-08-13T00:47:38.755646Z","end":"2026-08-13T00:47:39.149586Z","steps":["trace[1862924003] 'agreement among raft nodes before linearized reading' (duration: 393.88016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:39.149619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:38.755623Z","time spent":"393.990385ms","remote":"127.0.0.1:52920","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-13T00:47:39.149748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.491546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-08-13T00:47:39.149779Z","caller":"traceutil/trace.go:171","msg":"trace[1384369752] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:322; }","duration":"480.555319ms","start":"2026-08-13T00:47:38.669217Z","end":"2026-08-13T00:47:39.149772Z","steps":["trace[1384369752] 'agreement among raft nodes before linearized reading' (duration: 480.508897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:39.149809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:38.669195Z","time spent":"480.607712ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-08-13T00:47:39.149935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.69015ms","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-13T00:47:39.149965Z","caller":"traceutil/trace.go:171","msg":"trace[1293342105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:322; }","duration":"512.747494ms","start":"2026-08-13T00:47:38.637211Z","end":"2026-08-13T00:47:39.149958Z","steps":["trace[1293342105] 'agreement among raft nodes before linearized reading' (duration: 512.701221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:39.149996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:38.637189Z","time spent":"512.799716ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T00:47:39.150149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.216612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-08-13T00:47:39.150181Z","caller":"traceutil/trace.go:171","msg":"trace[1296478401] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:322; }","duration":"531.276684ms","start":"2026-08-13T00:47:38.618897Z","end":"2026-08-13T00:47:39.150174Z","steps":["trace[1296478401] 'agreement among raft nodes before linearized reading' (duration: 531.200261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:39.150207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:38.618879Z","time spent":"531.321566ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-08-13T00:47:39.9003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153701238623197959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T00:47:40.400566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153701238623197959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T00:47:40.544127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.387787204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-13T00:47:40.544207Z","caller":"traceutil/trace.go:171","msg":"trace[1687612104] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:322; }","duration":"1.38791776s","start":"2026-08-13T00:47:39.156271Z","end":"2026-08-13T00:47:40.544189Z","steps":["trace[1687612104] 'range keys from in-memory index tree' (duration: 1.38771021s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:40.544256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:39.156257Z","time spent":"1.387980022s","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-08-13T00:47:40.545474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326596654s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:47:40.545582Z","caller":"traceutil/trace.go:171","msg":"trace[2039093533] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"1.387303482s","start":"2026-08-13T00:47:39.158262Z","end":"2026-08-13T00:47:40.545566Z","steps":["trace[2039093533] 'process raft request' (duration: 60.365972ms)","trace[2039093533] 'compare' (duration: 1.3258224s)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:47:40.545643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:39.158236Z","time spent":"1.387380945s","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:47:40.555171Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.336501781s","expected-duration":"1s"} {"level":"info","ts":"2026-08-13T00:47:40.555524Z","caller":"traceutil/trace.go:171","msg":"trace[2044045148] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"1.393312773s","start":"2026-08-13T00:47:39.162188Z","end":"2026-08-13T00:47:40.555501Z","steps":["trace[2044045148] 'process raft request' (duration: 1.393153136s)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:47:40.555612Z","caller":"traceutil/trace.go:171","msg":"trace[1106773753] linearizableReadLoop","detail":"{readStateIndex:343; appliedIndex:337; }","duration":"1.156289572s","start":"2026-08-13T00:47:39.399308Z","end":"2026-08-13T00:47:40.555598Z","steps":["trace[1106773753] 'read index received' (duration: 1.156069573s)","trace[1106773753] 'applied index is now lower than readState.Index' (duration: 219.179µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:47:40.555783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:39.162174Z","time spent":"1.393410458s","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:47:40.555795Z","caller":"traceutil/trace.go:171","msg":"trace[391008288] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"1.393586355s","start":"2026-08-13T00:47:39.162199Z","end":"2026-08-13T00:47:40.555786Z","steps":["trace[391008288] 'process raft request' (duration: 1.393226079s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:40.555873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:39.162187Z","time spent":"1.393653078s","remote":"127.0.0.1:52922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T00:47:40.556037Z","caller":"traceutil/trace.go:171","msg":"trace[1344926909] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"1.393792925s","start":"2026-08-13T00:47:39.162235Z","end":"2026-08-13T00:47:40.556028Z","steps":["trace[1344926909] 'process raft request' (duration: 1.39323351s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:40.556093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:39.162225Z","time spent":"1.393841026s","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:47:40.556173Z","caller":"traceutil/trace.go:171","msg":"trace[1817402150] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"1.393887979s","start":"2026-08-13T00:47:39.162277Z","end":"2026-08-13T00:47:40.556165Z","steps":["trace[1817402150] 'process raft request' (duration: 1.393224529s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:40.556231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:39.162259Z","time spent":"1.393939761s","remote":"127.0.0.1:52746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T00:47:40.556244Z","caller":"traceutil/trace.go:171","msg":"trace[1850749892] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"1.393339764s","start":"2026-08-13T00:47:39.16288Z","end":"2026-08-13T00:47:40.55622Z","steps":["trace[1850749892] 'process raft request' (duration: 1.392686815s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:40.556371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157052677s","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-13T00:47:40.556408Z","caller":"traceutil/trace.go:171","msg":"trace[1005243413] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"1.39412345s","start":"2026-08-13T00:47:39.162277Z","end":"2026-08-13T00:47:40.5564Z","steps":["trace[1005243413] 'process raft request' (duration: 1.39325382s)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:47:40.556417Z","caller":"traceutil/trace.go:171","msg":"trace[953826063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:329; }","duration":"1.157129611s","start":"2026-08-13T00:47:39.399272Z","end":"2026-08-13T00:47:40.556402Z","steps":["trace[953826063] 'agreement among raft nodes before linearized reading' (duration: 1.157048017s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:40.556449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:39.399255Z","time spent":"1.157188463s","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T00:47:40.556452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:39.162258Z","time spent":"1.394168702s","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:47:40.556368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:39.162874Z","time spent":"1.393433288s","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:47:40.832717Z","caller":"traceutil/trace.go:171","msg":"trace[2091940616] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"280.845089ms","start":"2026-08-13T00:47:40.551823Z","end":"2026-08-13T00:47:40.832668Z","steps":["trace[2091940616] 'process raft request' (duration: 275.651185ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:47:40.832799Z","caller":"traceutil/trace.go:171","msg":"trace[1240993480] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:343; }","duration":"277.11852ms","start":"2026-08-13T00:47:40.555655Z","end":"2026-08-13T00:47:40.832774Z","steps":["trace[1240993480] 'read index received' (duration: 271.806581ms)","trace[1240993480] 'applied index is now lower than readState.Index' (duration: 5.308719ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:47:40.832936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236994598s","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-13T00:47:40.832963Z","caller":"traceutil/trace.go:171","msg":"trace[415661911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:330; }","duration":"1.237068811s","start":"2026-08-13T00:47:39.595888Z","end":"2026-08-13T00:47:40.832956Z","steps":["trace[415661911] 'agreement among raft nodes before linearized reading' (duration: 1.236958506s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:40.832983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:39.595868Z","time spent":"1.237111263s","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-13T00:47:40.83353Z","caller":"traceutil/trace.go:171","msg":"trace[653994473] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"269.662395ms","start":"2026-08-13T00:47:40.563858Z","end":"2026-08-13T00:47:40.833521Z","steps":["trace[653994473] 'process raft request' (duration: 269.635274ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:47:40.833641Z","caller":"traceutil/trace.go:171","msg":"trace[184992704] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"270.128486ms","start":"2026-08-13T00:47:40.563508Z","end":"2026-08-13T00:47:40.833636Z","steps":["trace[184992704] 'process raft request' (duration: 269.823812ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:47:40.83378Z","caller":"traceutil/trace.go:171","msg":"trace[486447737] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"270.878969ms","start":"2026-08-13T00:47:40.562896Z","end":"2026-08-13T00:47:40.833775Z","steps":["trace[486447737] 'process raft request' (duration: 269.744888ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:08.820146Z","caller":"traceutil/trace.go:171","msg":"trace[1943821944] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"357.037261ms","start":"2026-08-13T00:48:08.463079Z","end":"2026-08-13T00:48:08.820116Z","steps":["trace[1943821944] 'process raft request' (duration: 356.814421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:08.820364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:08.463051Z","time spent":"357.187299ms","remote":"127.0.0.1:52718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T00:48:08.827209Z","caller":"traceutil/trace.go:171","msg":"trace[729462034] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:505; }","duration":"114.7644ms","start":"2026-08-13T00:48:08.712416Z","end":"2026-08-13T00:48:08.82718Z","steps":["trace[729462034] 'read index received' (duration: 107.518323ms)","trace[729462034] 'applied index is now lower than readState.Index' (duration: 7.245427ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:48:08.82748Z","caller":"traceutil/trace.go:171","msg":"trace[312699198] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"347.415814ms","start":"2026-08-13T00:48:08.480026Z","end":"2026-08-13T00:48:08.827442Z","steps":["trace[312699198] 'process raft request' (duration: 346.647748ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:08.827604Z","caller":"traceutil/trace.go:171","msg":"trace[1885767675] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"339.113408ms","start":"2026-08-13T00:48:08.488473Z","end":"2026-08-13T00:48:08.827587Z","steps":["trace[1885767675] 'process raft request' (duration: 338.234607ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:08.827644Z","caller":"traceutil/trace.go:171","msg":"trace[1838758698] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"300.925711ms","start":"2026-08-13T00:48:08.526714Z","end":"2026-08-13T00:48:08.82764Z","steps":["trace[1838758698] 'process raft request' (duration: 300.26283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:08.827737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:08.526679Z","time spent":"300.992204ms","remote":"127.0.0.1:52718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T00:48:08.827583Z","caller":"traceutil/trace.go:171","msg":"trace[934778759] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"359.35813ms","start":"2026-08-13T00:48:08.468183Z","end":"2026-08-13T00:48:08.827541Z","steps":["trace[934778759] 'process raft request' (duration: 358.353123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:08.827811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:08.488451Z","time spent":"339.219563ms","remote":"127.0.0.1:52718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T00:48:08.827857Z","caller":"traceutil/trace.go:171","msg":"trace[1324132395] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"173.267122ms","start":"2026-08-13T00:48:08.654586Z","end":"2026-08-13T00:48:08.827853Z","steps":["trace[1324132395] 'process raft request' (duration: 172.544259ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:08.827623Z","caller":"traceutil/trace.go:171","msg":"trace[948732781] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"334.966725ms","start":"2026-08-13T00:48:08.49265Z","end":"2026-08-13T00:48:08.827616Z","steps":["trace[948732781] 'process raft request' (duration: 334.271653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:08.827935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:08.492642Z","time spent":"335.276429ms","remote":"127.0.0.1:52718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T00:48:08.827836Z","caller":"traceutil/trace.go:171","msg":"trace[1320478751] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"287.909475ms","start":"2026-08-13T00:48:08.539922Z","end":"2026-08-13T00:48:08.827831Z","steps":["trace[1320478751] 'process raft request' (duration: 287.138769ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:08.827981Z","caller":"traceutil/trace.go:171","msg":"trace[1846038350] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"339.254294ms","start":"2026-08-13T00:48:08.488722Z","end":"2026-08-13T00:48:08.827976Z","steps":["trace[1846038350] 'process raft request' (duration: 338.070619ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:08.827596Z","caller":"traceutil/trace.go:171","msg":"trace[91742107] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"338.784293ms","start":"2026-08-13T00:48:08.488798Z","end":"2026-08-13T00:48:08.827583Z","steps":["trace[91742107] 'process raft request' (duration: 338.08841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:08.828031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:08.488714Z","time spent":"339.286556ms","remote":"127.0.0.1:52718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T00:48:08.827514Z","caller":"traceutil/trace.go:171","msg":"trace[1816690436] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"338.72057ms","start":"2026-08-13T00:48:08.488713Z","end":"2026-08-13T00:48:08.827434Z","steps":["trace[1816690436] 'process raft request' (duration: 338.109601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:08.828055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:08.488792Z","time spent":"339.219753ms","remote":"127.0.0.1:52718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T00:48:08.827996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:08.468161Z","time spent":"359.635393ms","remote":"127.0.0.1:52718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T00:48:08.828095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:08.4887Z","time spent":"339.38139ms","remote":"127.0.0.1:52718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T00:48:08.827555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.98623ms","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-13T00:48:08.827903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:08.479995Z","time spent":"347.639704ms","remote":"127.0.0.1:52718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T00:48:08.82822Z","caller":"traceutil/trace.go:171","msg":"trace[619696182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"115.841829ms","start":"2026-08-13T00:48:08.712355Z","end":"2026-08-13T00:48:08.828197Z","steps":["trace[619696182] 'agreement among raft nodes before linearized reading' (duration: 114.925767ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:08.828147Z","caller":"traceutil/trace.go:171","msg":"trace[1747291893] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"359.889915ms","start":"2026-08-13T00:48:08.468251Z","end":"2026-08-13T00:48:08.828141Z","steps":["trace[1747291893] 'process raft request' (duration: 358.390945ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:08.828592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:08.468245Z","time spent":"360.314654ms","remote":"127.0.0.1:52718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T00:48:08.835679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.563183ms","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-13T00:48:08.835759Z","caller":"traceutil/trace.go:171","msg":"trace[1439406945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:498; }","duration":"119.671528ms","start":"2026-08-13T00:48:08.716074Z","end":"2026-08-13T00:48:08.835746Z","steps":["trace[1439406945] 'agreement among raft nodes before linearized reading' (duration: 119.532312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:15.434724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.58839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tnv9v\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-08-13T00:48:15.434773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.321255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T00:48:15.434768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.476973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-13T00:48:15.4349Z","caller":"traceutil/trace.go:171","msg":"trace[2076614664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"182.488323ms","start":"2026-08-13T00:48:15.252368Z","end":"2026-08-13T00:48:15.434856Z","steps":["trace[2076614664] 'range keys from in-memory index tree' (duration: 182.225141ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:15.434913Z","caller":"traceutil/trace.go:171","msg":"trace[1200625746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"395.670532ms","start":"2026-08-13T00:48:15.039214Z","end":"2026-08-13T00:48:15.434884Z","steps":["trace[1200625746] 'range keys from in-memory index tree' (duration: 395.260043ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:15.43481Z","caller":"traceutil/trace.go:171","msg":"trace[1694614513] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tnv9v; range_end:; response_count:1; response_revision:595; }","duration":"432.733517ms","start":"2026-08-13T00:48:15.002053Z","end":"2026-08-13T00:48:15.434787Z","steps":["trace[1694614513] 'range keys from in-memory index tree' (duration: 432.37461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:15.434978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:15.039193Z","time spent":"395.767747ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T00:48:15.434724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.983954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-13T00:48:15.435042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:15.002031Z","time spent":"432.991319ms","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-tnv9v\" "} {"level":"info","ts":"2026-08-13T00:48:15.435093Z","caller":"traceutil/trace.go:171","msg":"trace[1636942710] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"425.367712ms","start":"2026-08-13T00:48:15.009686Z","end":"2026-08-13T00:48:15.435054Z","steps":["trace[1636942710] 'range keys from in-memory index tree' (duration: 424.865619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:15.435168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:15.009669Z","time spent":"425.475688ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T00:48:15.519022Z","caller":"traceutil/trace.go:171","msg":"trace[1699541133] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"209.524558ms","start":"2026-08-13T00:48:15.309475Z","end":"2026-08-13T00:48:15.519Z","steps":["trace[1699541133] 'process raft request' (duration: 209.041775ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:15.626912Z","caller":"traceutil/trace.go:171","msg":"trace[215162670] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"187.454356ms","start":"2026-08-13T00:48:15.439434Z","end":"2026-08-13T00:48:15.626889Z","steps":["trace[215162670] 'process raft request' (duration: 187.140821ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:15.626965Z","caller":"traceutil/trace.go:171","msg":"trace[1071172207] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"187.246025ms","start":"2026-08-13T00:48:15.439674Z","end":"2026-08-13T00:48:15.62692Z","steps":["trace[1071172207] 'process raft request' (duration: 187.110899ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:15.627209Z","caller":"traceutil/trace.go:171","msg":"trace[280483240] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"175.613881ms","start":"2026-08-13T00:48:15.451534Z","end":"2026-08-13T00:48:15.627148Z","steps":["trace[280483240] 'process raft request' (duration: 175.293006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:16.318947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.082224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:48:16.319288Z","caller":"traceutil/trace.go:171","msg":"trace[1741955660] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"733.603705ms","start":"2026-08-13T00:48:15.585658Z","end":"2026-08-13T00:48:16.319262Z","steps":["trace[1741955660] 'process raft request' (duration: 590.148334ms)","trace[1741955660] 'compare' (duration: 142.944267ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:48:16.319353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:15.585633Z","time spent":"733.693409ms","remote":"127.0.0.1:52896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T00:48:17.393905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153701238623198480,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T00:48:17.461679Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.128288137s","expected-duration":"1s"} {"level":"info","ts":"2026-08-13T00:48:17.462107Z","caller":"traceutil/trace.go:171","msg":"trace[524496903] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"569.250537ms","start":"2026-08-13T00:48:16.892822Z","end":"2026-08-13T00:48:17.462073Z","steps":["trace[524496903] 'read index received' (duration: 569.067909ms)","trace[524496903] 'applied index is now lower than readState.Index' (duration: 181.558µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:48:17.462248Z","caller":"traceutil/trace.go:171","msg":"trace[287245094] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.128945349s","start":"2026-08-13T00:48:16.333286Z","end":"2026-08-13T00:48:17.462232Z","steps":["trace[287245094] 'process raft request' (duration: 1.128646075s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:17.462375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:16.333267Z","time spent":"1.129045913s","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T00:48:17.462696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.821464ms","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-13T00:48:17.462832Z","caller":"traceutil/trace.go:171","msg":"trace[1982543312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"570.013323ms","start":"2026-08-13T00:48:16.892774Z","end":"2026-08-13T00:48:17.462787Z","steps":["trace[1982543312] 'agreement among raft nodes before linearized reading' (duration: 569.582513ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:17.462946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:16.892752Z","time spent":"570.17773ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T00:48:17.536592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.277176ms","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-13T00:48:17.536662Z","caller":"traceutil/trace.go:171","msg":"trace[1560586740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"615.388032ms","start":"2026-08-13T00:48:16.921259Z","end":"2026-08-13T00:48:17.536647Z","steps":["trace[1560586740] 'agreement among raft nodes before linearized reading' (duration: 615.151701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:17.536703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:16.921243Z","time spent":"615.452054ms","remote":"127.0.0.1:52920","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":"info","ts":"2026-08-13T00:48:29.213658Z","caller":"traceutil/trace.go:171","msg":"trace[1065296331] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"121.258025ms","start":"2026-08-13T00:48:29.092379Z","end":"2026-08-13T00:48:29.213637Z","steps":["trace[1065296331] 'process raft request' (duration: 121.082477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:32.436201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.142652ms","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-13T00:48:32.436301Z","caller":"traceutil/trace.go:171","msg":"trace[437878257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"837.261367ms","start":"2026-08-13T00:48:31.599006Z","end":"2026-08-13T00:48:32.436267Z","steps":["trace[437878257] 'range keys from in-memory index tree' (duration: 836.997455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:32.436366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:31.598998Z","time spent":"837.353151ms","remote":"127.0.0.1:52920","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-13T00:48:32.437793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.454685ms","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-13T00:48:32.437894Z","caller":"traceutil/trace.go:171","msg":"trace[1387000796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"739.54584ms","start":"2026-08-13T00:48:31.69831Z","end":"2026-08-13T00:48:32.437856Z","steps":["trace[1387000796] 'range keys from in-memory index tree' (duration: 738.009626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:32.437937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:31.698299Z","time spent":"739.627583ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T00:48:32.438101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.660861ms","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-13T00:48:32.438167Z","caller":"traceutil/trace.go:171","msg":"trace[1013361881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"839.769566ms","start":"2026-08-13T00:48:31.598378Z","end":"2026-08-13T00:48:32.438147Z","steps":["trace[1013361881] 'range keys from in-memory index tree' (duration: 839.507874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:32.438231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:31.598345Z","time spent":"839.85345ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T00:48:32.438442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.959506ms","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-13T00:48:32.438533Z","caller":"traceutil/trace.go:171","msg":"trace[1888957207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"741.089833ms","start":"2026-08-13T00:48:31.697421Z","end":"2026-08-13T00:48:32.438511Z","steps":["trace[1888957207] 'range keys from in-memory index tree' (duration: 740.794388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:32.438592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:31.6974Z","time spent":"741.165876ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T00:48:32.712231Z","caller":"traceutil/trace.go:171","msg":"trace[528280235] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"627.655026ms","start":"2026-08-13T00:48:32.084552Z","end":"2026-08-13T00:48:32.712207Z","steps":["trace[528280235] 'process raft request' (duration: 627.463016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:32.712403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:32.084527Z","time spent":"627.799202ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:48:32.717998Z","caller":"traceutil/trace.go:171","msg":"trace[1456748758] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"610.833279ms","start":"2026-08-13T00:48:32.107138Z","end":"2026-08-13T00:48:32.717972Z","steps":["trace[1456748758] 'process raft request' (duration: 610.64702ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:32.718071Z","caller":"traceutil/trace.go:171","msg":"trace[835052182] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"274.478198ms","start":"2026-08-13T00:48:32.443557Z","end":"2026-08-13T00:48:32.718035Z","steps":["trace[835052182] 'process raft request' (duration: 274.331361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:32.718128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:32.107114Z","time spent":"610.946664ms","remote":"127.0.0.1:52896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:48:32.718159Z","caller":"traceutil/trace.go:171","msg":"trace[1279294286] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"274.499229ms","start":"2026-08-13T00:48:32.443623Z","end":"2026-08-13T00:48:32.718123Z","steps":["trace[1279294286] 'process raft request' (duration: 274.3038ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:32.71831Z","caller":"traceutil/trace.go:171","msg":"trace[1495040451] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"273.912381ms","start":"2026-08-13T00:48:32.444389Z","end":"2026-08-13T00:48:32.718301Z","steps":["trace[1495040451] 'process raft request' (duration: 273.570385ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:32.718812Z","caller":"traceutil/trace.go:171","msg":"trace[155214389] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"274.197405ms","start":"2026-08-13T00:48:32.444603Z","end":"2026-08-13T00:48:32.7188Z","steps":["trace[155214389] 'process raft request' (duration: 274.10051ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:32.718826Z","caller":"traceutil/trace.go:171","msg":"trace[660825268] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:786; }","duration":"505.454364ms","start":"2026-08-13T00:48:32.213345Z","end":"2026-08-13T00:48:32.7188Z","steps":["trace[660825268] 'read index received' (duration: 498.763107ms)","trace[660825268] 'applied index is now lower than readState.Index' (duration: 6.690137ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:48:32.718978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.676297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T00:48:32.719004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.637953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-13T00:48:32.71904Z","caller":"traceutil/trace.go:171","msg":"trace[2004130222] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:761; }","duration":"505.734408ms","start":"2026-08-13T00:48:32.213295Z","end":"2026-08-13T00:48:32.719029Z","steps":["trace[2004130222] 'agreement among raft nodes before linearized reading' (duration: 505.57651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:32.719099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:32.213276Z","time spent":"505.815312ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.240\" "} {"level":"info","ts":"2026-08-13T00:48:32.71904Z","caller":"traceutil/trace.go:171","msg":"trace[509849926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"466.781352ms","start":"2026-08-13T00:48:32.252242Z","end":"2026-08-13T00:48:32.719024Z","steps":["trace[509849926] 'agreement among raft nodes before linearized reading' (duration: 466.684087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:32.719229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:32.252223Z","time spent":"466.971031ms","remote":"127.0.0.1:52672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T00:49:26.8497Z","caller":"traceutil/trace.go:171","msg":"trace[783379513] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1477; }","duration":"204.618899ms","start":"2026-08-13T00:49:26.64506Z","end":"2026-08-13T00:49:26.849679Z","steps":["trace[783379513] 'read index received' (duration: 197.408469ms)","trace[783379513] 'applied index is now lower than readState.Index' (duration: 7.20937ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:49:26.849733Z","caller":"traceutil/trace.go:171","msg":"trace[837380949] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"357.854926ms","start":"2026-08-13T00:49:26.491852Z","end":"2026-08-13T00:49:26.849707Z","steps":["trace[837380949] 'process raft request' (duration: 350.528601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:26.849808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.739344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T00:49:26.84984Z","caller":"traceutil/trace.go:171","msg":"trace[494129585] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1428; }","duration":"204.816738ms","start":"2026-08-13T00:49:26.64501Z","end":"2026-08-13T00:49:26.849827Z","steps":["trace[494129585] 'agreement among raft nodes before linearized reading' (duration: 204.745434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:26.849885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:26.491828Z","time spent":"357.982942ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:49:26.858962Z","caller":"traceutil/trace.go:171","msg":"trace[1365458735] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"166.549503ms","start":"2026-08-13T00:49:26.692397Z","end":"2026-08-13T00:49:26.858946Z","steps":["trace[1365458735] 'process raft request' (duration: 166.464909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:27.206257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.017505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-13T00:49:27.206332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.518256ms","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-13T00:49:27.206346Z","caller":"traceutil/trace.go:171","msg":"trace[146352977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1429; }","duration":"221.161382ms","start":"2026-08-13T00:49:26.985165Z","end":"2026-08-13T00:49:27.206327Z","steps":["trace[146352977] 'range keys from in-memory index tree' (duration: 220.812996ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:27.206413Z","caller":"traceutil/trace.go:171","msg":"trace[1875276916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1429; }","duration":"217.648863ms","start":"2026-08-13T00:49:26.988746Z","end":"2026-08-13T00:49:27.206395Z","steps":["trace[1875276916] 'range keys from in-memory index tree' (duration: 217.290015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:27.206873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.200552ms","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-13T00:49:27.20693Z","caller":"traceutil/trace.go:171","msg":"trace[42463766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1429; }","duration":"216.277426ms","start":"2026-08-13T00:49:26.990637Z","end":"2026-08-13T00:49:27.206914Z","steps":["trace[42463766] 'range keys from in-memory index tree' (duration: 216.073975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:27.206906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.123849ms","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-13T00:49:27.207387Z","caller":"traceutil/trace.go:171","msg":"trace[819981610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1429; }","duration":"216.626793ms","start":"2026-08-13T00:49:26.990736Z","end":"2026-08-13T00:49:27.207362Z","steps":["trace[819981610] 'range keys from in-memory index tree' (duration: 215.712989ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:28.169193Z","caller":"traceutil/trace.go:171","msg":"trace[743878357] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"957.977909ms","start":"2026-08-13T00:49:27.211169Z","end":"2026-08-13T00:49:28.169147Z","steps":["trace[743878357] 'process raft request' (duration: 957.659114ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:28.169294Z","caller":"traceutil/trace.go:171","msg":"trace[1232954369] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"958.084565ms","start":"2026-08-13T00:49:27.211191Z","end":"2026-08-13T00:49:28.169276Z","steps":["trace[1232954369] 'process raft request' (duration: 957.894815ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:28.169384Z","caller":"traceutil/trace.go:171","msg":"trace[1689119629] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"958.20589ms","start":"2026-08-13T00:49:27.211166Z","end":"2026-08-13T00:49:28.169372Z","steps":["trace[1689119629] 'process raft request' (duration: 957.834122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:28.169426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:27.211176Z","time spent":"958.177058ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:49:28.169472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:27.211149Z","time spent":"958.274984ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:49:28.169712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:27.21115Z","time spent":"958.136567ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:49:28.169229Z","caller":"traceutil/trace.go:171","msg":"trace[414558184] linearizableReadLoop","detail":"{readStateIndex:1483; appliedIndex:1479; }","duration":"918.208461ms","start":"2026-08-13T00:49:27.250998Z","end":"2026-08-13T00:49:28.169207Z","steps":["trace[414558184] 'read index received' (duration: 49.055479ms)","trace[414558184] 'applied index is now lower than readState.Index' (duration: 869.152172ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:49:28.169713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.687085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:49:28.169802Z","caller":"traceutil/trace.go:171","msg":"trace[1589147875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1433; }","duration":"918.836051ms","start":"2026-08-13T00:49:27.250945Z","end":"2026-08-13T00:49:28.169782Z","steps":["trace[1589147875] 'agreement among raft nodes before linearized reading' (duration: 918.643772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:28.169857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:27.250921Z","time spent":"918.925286ms","remote":"127.0.0.1:52672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T00:49:28.209189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.372846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T00:49:28.209252Z","caller":"traceutil/trace.go:171","msg":"trace[118992760] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1433; }","duration":"432.486551ms","start":"2026-08-13T00:49:27.776753Z","end":"2026-08-13T00:49:28.209239Z","steps":["trace[118992760] 'agreement among raft nodes before linearized reading' (duration: 432.264741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:28.209269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.846345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-13T00:49:28.209351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:27.776727Z","time spent":"432.552824ms","remote":"127.0.0.1:52970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":21,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-08-13T00:49:28.209368Z","caller":"traceutil/trace.go:171","msg":"trace[215099114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1433; }","duration":"943.951629ms","start":"2026-08-13T00:49:27.265367Z","end":"2026-08-13T00:49:28.209319Z","steps":["trace[215099114] 'agreement among raft nodes before linearized reading' (duration: 943.738999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:28.209495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:27.265352Z","time spent":"944.064795ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-13T00:49:30.021298Z","caller":"traceutil/trace.go:171","msg":"trace[2051115372] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1447; }","duration":"154.315677ms","start":"2026-08-13T00:49:29.866959Z","end":"2026-08-13T00:49:30.021275Z","steps":["trace[2051115372] 'process raft request' (duration: 98.867436ms)","trace[2051115372] 'compare' (duration: 54.453453ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:49:37.983283Z","caller":"traceutil/trace.go:171","msg":"trace[255062161] transaction","detail":"{read_only:false; response_revision:1554; number_of_response:1; }","duration":"110.610166ms","start":"2026-08-13T00:49:37.872644Z","end":"2026-08-13T00:49:37.983254Z","steps":["trace[255062161] 'process raft request' (duration: 63.628103ms)","trace[255062161] 'compare' (duration: 46.585404ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:49:37.983346Z","caller":"traceutil/trace.go:171","msg":"trace[1465014610] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"110.602746ms","start":"2026-08-13T00:49:37.872703Z","end":"2026-08-13T00:49:37.983306Z","steps":["trace[1465014610] 'process raft request' (duration: 110.407336ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:37.983511Z","caller":"traceutil/trace.go:171","msg":"trace[307129844] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"109.803317ms","start":"2026-08-13T00:49:37.873692Z","end":"2026-08-13T00:49:37.983496Z","steps":["trace[307129844] 'process raft request' (duration: 109.589166ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:37.983628Z","caller":"traceutil/trace.go:171","msg":"trace[57009426] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"109.958354ms","start":"2026-08-13T00:49:37.873661Z","end":"2026-08-13T00:49:37.98362Z","steps":["trace[57009426] 'process raft request' (duration: 109.587816ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:37.983734Z","caller":"traceutil/trace.go:171","msg":"trace[40721414] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"109.936793ms","start":"2026-08-13T00:49:37.873723Z","end":"2026-08-13T00:49:37.983659Z","steps":["trace[40721414] 'process raft request' (duration: 109.584856ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:37.983546Z","caller":"traceutil/trace.go:171","msg":"trace[2136894013] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"109.910592ms","start":"2026-08-13T00:49:37.873607Z","end":"2026-08-13T00:49:37.983517Z","steps":["trace[2136894013] 'process raft request' (duration: 109.595017ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:37.98372Z","caller":"traceutil/trace.go:171","msg":"trace[2018728753] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"109.160575ms","start":"2026-08-13T00:49:37.874543Z","end":"2026-08-13T00:49:37.983704Z","steps":["trace[2018728753] 'process raft request' (duration: 108.790467ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:40.482115Z","caller":"traceutil/trace.go:171","msg":"trace[1982412190] linearizableReadLoop","detail":"{readStateIndex:1730; appliedIndex:1729; }","duration":"140.02896ms","start":"2026-08-13T00:49:40.342058Z","end":"2026-08-13T00:49:40.482087Z","steps":["trace[1982412190] 'read index received' (duration: 128.951311ms)","trace[1982412190] 'applied index is now lower than readState.Index' (duration: 11.076599ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:49:40.482203Z","caller":"traceutil/trace.go:171","msg":"trace[35386486] transaction","detail":"{read_only:false; response_revision:1659; number_of_response:1; }","duration":"142.585944ms","start":"2026-08-13T00:49:40.339583Z","end":"2026-08-13T00:49:40.482169Z","steps":["trace[35386486] 'process raft request' (duration: 131.480794ms)","trace[35386486] 'compare' (duration: 10.859268ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:49:40.482496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.409168ms","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-13T00:49:40.482848Z","caller":"traceutil/trace.go:171","msg":"trace[1731973448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1659; }","duration":"140.809498ms","start":"2026-08-13T00:49:40.342022Z","end":"2026-08-13T00:49:40.482831Z","steps":["trace[1731973448] 'agreement among raft nodes before linearized reading' (duration: 140.240001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:40.48285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.581809ms","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-13T00:49:40.482936Z","caller":"traceutil/trace.go:171","msg":"trace[333417442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1659; }","duration":"124.721106ms","start":"2026-08-13T00:49:40.358199Z","end":"2026-08-13T00:49:40.48292Z","steps":["trace[333417442] 'agreement among raft nodes before linearized reading' (duration: 124.481034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:40.482838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.552486ms","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-13T00:49:40.483183Z","caller":"traceutil/trace.go:171","msg":"trace[1459544459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1659; }","duration":"110.910083ms","start":"2026-08-13T00:49:40.372235Z","end":"2026-08-13T00:49:40.483145Z","steps":["trace[1459544459] 'agreement among raft nodes before linearized reading' (duration: 110.42893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:40.483346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.256472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:49:40.483383Z","caller":"traceutil/trace.go:171","msg":"trace[462040368] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com; range_end:; response_count:0; response_revision:1659; }","duration":"132.321925ms","start":"2026-08-13T00:49:40.351051Z","end":"2026-08-13T00:49:40.483373Z","steps":["trace[462040368] 'agreement among raft nodes before linearized reading' (duration: 131.591519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:58.183739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.041083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:49:58.183974Z","caller":"traceutil/trace.go:171","msg":"trace[1529485942] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"695.983447ms","start":"2026-08-13T00:49:57.487939Z","end":"2026-08-13T00:49:58.183922Z","steps":["trace[1529485942] 'process raft request' (duration: 43.609661ms)","trace[1529485942] 'compare' (duration: 651.811691ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:49:58.184039Z","caller":"traceutil/trace.go:171","msg":"trace[1356537927] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"681.118264ms","start":"2026-08-13T00:49:57.502832Z","end":"2026-08-13T00:49:58.18395Z","steps":["trace[1356537927] 'read index received' (duration: 28.657188ms)","trace[1356537927] 'applied index is now lower than readState.Index' (duration: 652.439006ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:49:58.184189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:57.487912Z","time spent":"696.199179ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:49:58.247849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.995308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cbefb2cc-4e25-45fa-b098-9ad017eeb2ee\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-13T00:49:58.247945Z","caller":"traceutil/trace.go:171","msg":"trace[745614857] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cbefb2cc-4e25-45fa-b098-9ad017eeb2ee; range_end:; response_count:1; response_revision:1863; }","duration":"745.12228ms","start":"2026-08-13T00:49:57.502792Z","end":"2026-08-13T00:49:58.247914Z","steps":["trace[745614857] 'agreement among raft nodes before linearized reading' (duration: 681.359237ms)","trace[745614857] 'range keys from in-memory index tree' (duration: 63.560502ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:49:58.248031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:57.502774Z","time spent":"745.240561ms","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cbefb2cc-4e25-45fa-b098-9ad017eeb2ee\" "} {"level":"warn","ts":"2026-08-13T00:49:58.24835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.119801ms","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-13T00:49:58.248344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.695123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-13T00:49:58.248394Z","caller":"traceutil/trace.go:171","msg":"trace[1779475531] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1864; }","duration":"171.198292ms","start":"2026-08-13T00:49:58.077185Z","end":"2026-08-13T00:49:58.248383Z","steps":["trace[1779475531] 'agreement among raft nodes before linearized reading' (duration: 171.095531ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:58.248406Z","caller":"traceutil/trace.go:171","msg":"trace[1152038926] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1864; }","duration":"298.794474ms","start":"2026-08-13T00:49:57.949598Z","end":"2026-08-13T00:49:58.248393Z","steps":["trace[1152038926] 'agreement among raft nodes before linearized reading' (duration: 298.629813ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:58.248485Z","caller":"traceutil/trace.go:171","msg":"trace[753152225] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"554.530609ms","start":"2026-08-13T00:49:57.693929Z","end":"2026-08-13T00:49:58.248459Z","steps":["trace[753152225] 'process raft request' (duration: 553.826364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:58.248527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.838318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T00:49:58.248606Z","caller":"traceutil/trace.go:171","msg":"trace[1722106324] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1864; }","duration":"635.961099ms","start":"2026-08-13T00:49:57.612601Z","end":"2026-08-13T00:49:58.248562Z","steps":["trace[1722106324] 'agreement among raft nodes before linearized reading' (duration: 635.835058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:58.248639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:57.693908Z","time spent":"554.6518ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:49:58.248652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:57.612584Z","time spent":"636.0563ms","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":23,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-08-13T00:49:58.24909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.345018ms","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-13T00:49:58.249158Z","caller":"traceutil/trace.go:171","msg":"trace[1374418326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1864; }","duration":"717.49656ms","start":"2026-08-13T00:49:57.531646Z","end":"2026-08-13T00:49:58.249143Z","steps":["trace[1374418326] 'agreement among raft nodes before linearized reading' (duration: 716.446681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:58.2492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:57.53162Z","time spent":"717.57029ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T00:49:58.37777Z","caller":"traceutil/trace.go:171","msg":"trace[1081881374] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"123.047972ms","start":"2026-08-13T00:49:58.254692Z","end":"2026-08-13T00:49:58.37774Z","steps":["trace[1081881374] 'process raft request' (duration: 89.783829ms)","trace[1081881374] 'compare' (duration: 32.969671ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:49:58.810731Z","caller":"traceutil/trace.go:171","msg":"trace[387409562] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1944; }","duration":"251.662327ms","start":"2026-08-13T00:49:58.559045Z","end":"2026-08-13T00:49:58.810707Z","steps":["trace[387409562] 'read index received' (duration: 246.202754ms)","trace[387409562] 'applied index is now lower than readState.Index' (duration: 5.458223ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:49:58.810817Z","caller":"traceutil/trace.go:171","msg":"trace[1945872152] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"553.351359ms","start":"2026-08-13T00:49:58.257436Z","end":"2026-08-13T00:49:58.810787Z","steps":["trace[1945872152] 'process raft request' (duration: 553.170927ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:58.810948Z","caller":"traceutil/trace.go:171","msg":"trace[944162618] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"556.200121ms","start":"2026-08-13T00:49:58.254724Z","end":"2026-08-13T00:49:58.810924Z","steps":["trace[944162618] 'process raft request' (duration: 550.573886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:58.810971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.924279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-08-13T00:49:58.811013Z","caller":"traceutil/trace.go:171","msg":"trace[68758300] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1867; }","duration":"252.0654ms","start":"2026-08-13T00:49:58.558937Z","end":"2026-08-13T00:49:58.811002Z","steps":["trace[68758300] 'agreement among raft nodes before linearized reading' (duration: 251.898609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:58.810997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:58.257425Z","time spent":"553.48374ms","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:49:58.811052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:58.254699Z","time spent":"556.293932ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:49:58.811188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.803941ms","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-13T00:49:58.811234Z","caller":"traceutil/trace.go:171","msg":"trace[1147903686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1867; }","duration":"221.879692ms","start":"2026-08-13T00:49:58.589342Z","end":"2026-08-13T00:49:58.811222Z","steps":["trace[1147903686] 'agreement among raft nodes before linearized reading' (duration: 221.735351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:58.811274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.287564ms","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-13T00:49:58.81132Z","caller":"traceutil/trace.go:171","msg":"trace[1409195599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1867; }","duration":"156.346675ms","start":"2026-08-13T00:49:58.654959Z","end":"2026-08-13T00:49:58.811306Z","steps":["trace[1409195599] 'agreement among raft nodes before linearized reading' (duration: 156.222174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:58.81134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.416501ms","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-13T00:49:58.811393Z","caller":"traceutil/trace.go:171","msg":"trace[386316840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1867; }","duration":"196.494291ms","start":"2026-08-13T00:49:58.614883Z","end":"2026-08-13T00:49:58.811377Z","steps":["trace[386316840] 'agreement among raft nodes before linearized reading' (duration: 196.34454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:58.811488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.073604ms","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-13T00:49:58.811533Z","caller":"traceutil/trace.go:171","msg":"trace[163387831] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1867; }","duration":"155.138345ms","start":"2026-08-13T00:49:58.656379Z","end":"2026-08-13T00:49:58.811518Z","steps":["trace[163387831] 'agreement among raft nodes before linearized reading' (duration: 155.031074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:59.198793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.482663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:49:59.19898Z","caller":"traceutil/trace.go:171","msg":"trace[993792316] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"201.299425ms","start":"2026-08-13T00:49:58.997594Z","end":"2026-08-13T00:49:59.198894Z","steps":["trace[993792316] 'process raft request' (duration: 68.61694ms)","trace[993792316] 'compare' (duration: 132.365292ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:49:59.665752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.983792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-13T00:49:59.665807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.948678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"info","ts":"2026-08-13T00:49:59.665859Z","caller":"traceutil/trace.go:171","msg":"trace[1893781454] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1873; }","duration":"158.192804ms","start":"2026-08-13T00:49:59.507643Z","end":"2026-08-13T00:49:59.665836Z","steps":["trace[1893781454] 'count revisions from in-memory index tree' (duration: 157.890481ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:59.665887Z","caller":"traceutil/trace.go:171","msg":"trace[1497854837] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1873; }","duration":"193.087379ms","start":"2026-08-13T00:49:59.472781Z","end":"2026-08-13T00:49:59.665869Z","steps":["trace[1497854837] 'range keys from in-memory index tree' (duration: 192.732636ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:12.461586Z","caller":"traceutil/trace.go:171","msg":"trace[2101896] linearizableReadLoop","detail":"{readStateIndex:2067; appliedIndex:2066; }","duration":"189.758576ms","start":"2026-08-13T00:50:12.271803Z","end":"2026-08-13T00:50:12.461561Z","steps":["trace[2101896] 'read index received' (duration: 189.549593ms)","trace[2101896] 'applied index is now lower than readState.Index' (duration: 204.893µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:50:12.46182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.977218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:50:12.461872Z","caller":"traceutil/trace.go:171","msg":"trace[1614040851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1985; }","duration":"190.089179ms","start":"2026-08-13T00:50:12.27177Z","end":"2026-08-13T00:50:12.461859Z","steps":["trace[1614040851] 'agreement among raft nodes before linearized reading' (duration: 189.942918ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:12.461712Z","caller":"traceutil/trace.go:171","msg":"trace[225533337] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"201.404332ms","start":"2026-08-13T00:50:12.260255Z","end":"2026-08-13T00:50:12.461659Z","steps":["trace[225533337] 'process raft request' (duration: 201.123189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:12.46215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.178161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-13T00:50:12.462226Z","caller":"traceutil/trace.go:171","msg":"trace[1745500036] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1985; }","duration":"119.346573ms","start":"2026-08-13T00:50:12.34286Z","end":"2026-08-13T00:50:12.462206Z","steps":["trace[1745500036] 'agreement among raft nodes before linearized reading' (duration: 119.09547ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:13.188985Z","caller":"traceutil/trace.go:171","msg":"trace[1947774221] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"160.555345ms","start":"2026-08-13T00:50:13.028374Z","end":"2026-08-13T00:50:13.18893Z","steps":["trace[1947774221] 'process raft request' (duration: 157.193475ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:13.189175Z","caller":"traceutil/trace.go:171","msg":"trace[931995408] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"159.177089ms","start":"2026-08-13T00:50:13.029986Z","end":"2026-08-13T00:50:13.189163Z","steps":["trace[931995408] 'process raft request' (duration: 159.021057ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:13.189077Z","caller":"traceutil/trace.go:171","msg":"trace[1471191827] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"159.23579ms","start":"2026-08-13T00:50:13.029822Z","end":"2026-08-13T00:50:13.189057Z","steps":["trace[1471191827] 'process raft request' (duration: 158.994347ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:13.188933Z","caller":"traceutil/trace.go:171","msg":"trace[1049237080] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"160.434684ms","start":"2026-08-13T00:50:13.028472Z","end":"2026-08-13T00:50:13.188906Z","steps":["trace[1049237080] 'process raft request' (duration: 160.290682ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:13.407066Z","caller":"traceutil/trace.go:171","msg":"trace[643192251] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"148.207258ms","start":"2026-08-13T00:50:13.258821Z","end":"2026-08-13T00:50:13.407028Z","steps":["trace[643192251] 'process raft request' (duration: 76.094428ms)","trace[643192251] 'compare' (duration: 71.801247ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:50:15.865624Z","caller":"traceutil/trace.go:171","msg":"trace[1322127331] linearizableReadLoop","detail":"{readStateIndex:2087; appliedIndex:2086; }","duration":"381.390802ms","start":"2026-08-13T00:50:15.484206Z","end":"2026-08-13T00:50:15.865597Z","steps":["trace[1322127331] 'read index received' (duration: 381.200419ms)","trace[1322127331] 'applied index is now lower than readState.Index' (duration: 189.373µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:50:15.865859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.648275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-13T00:50:15.865894Z","caller":"traceutil/trace.go:171","msg":"trace[159738061] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2005; }","duration":"381.757676ms","start":"2026-08-13T00:50:15.484123Z","end":"2026-08-13T00:50:15.86588Z","steps":["trace[159738061] 'agreement among raft nodes before linearized reading' (duration: 381.573404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:15.865934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:15.484092Z","time spent":"381.831627ms","remote":"127.0.0.1:34760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-13T00:50:15.867124Z","caller":"traceutil/trace.go:171","msg":"trace[99853316] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"454.970256ms","start":"2026-08-13T00:50:15.412014Z","end":"2026-08-13T00:50:15.866984Z","steps":["trace[99853316] 'process raft request' (duration: 453.431947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:15.867348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:15.411958Z","time spent":"455.26772ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:50:39.529508Z","caller":"traceutil/trace.go:171","msg":"trace[1294112106] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"123.554371ms","start":"2026-08-13T00:50:39.405916Z","end":"2026-08-13T00:50:39.529471Z","steps":["trace[1294112106] 'process raft request' (duration: 95.203804ms)","trace[1294112106] 'compare' (duration: 28.194684ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:50:40.220242Z","caller":"traceutil/trace.go:171","msg":"trace[623129985] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2228; }","duration":"160.395643ms","start":"2026-08-13T00:50:40.059815Z","end":"2026-08-13T00:50:40.22021Z","steps":["trace[623129985] 'read index received' (duration: 159.799262ms)","trace[623129985] 'applied index is now lower than readState.Index' (duration: 594.871µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:50:40.220265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.086733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-08-13T00:50:40.221214Z","caller":"traceutil/trace.go:171","msg":"trace[1202620798] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2140; }","duration":"334.070301ms","start":"2026-08-13T00:50:39.887121Z","end":"2026-08-13T00:50:40.221191Z","steps":["trace[1202620798] 'range keys from in-memory index tree' (duration: 332.90281ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:40.220382Z","caller":"traceutil/trace.go:171","msg":"trace[1096523382] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"243.852167ms","start":"2026-08-13T00:50:39.976509Z","end":"2026-08-13T00:50:40.220362Z","steps":["trace[1096523382] 'process raft request' (duration: 243.178844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:40.220495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.695158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-08-13T00:50:40.221715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:39.887071Z","time spent":"334.56352ms","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12409,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-13T00:50:40.221786Z","caller":"traceutil/trace.go:171","msg":"trace[1040674727] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2141; }","duration":"162.006992ms","start":"2026-08-13T00:50:40.059752Z","end":"2026-08-13T00:50:40.221759Z","steps":["trace[1040674727] 'agreement among raft nodes before linearized reading' (duration: 160.668688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:40.220527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.948946ms","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-13T00:50:40.222315Z","caller":"traceutil/trace.go:171","msg":"trace[546432220] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2140; }","duration":"517.027734ms","start":"2026-08-13T00:50:39.705206Z","end":"2026-08-13T00:50:40.222234Z","steps":["trace[546432220] 'count revisions from in-memory index tree' (duration: 514.409056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:40.223394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:39.705182Z","time spent":"517.321439ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-13T00:50:40.807543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.146674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:50:40.808207Z","caller":"traceutil/trace.go:171","msg":"trace[1348286618] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"568.956046ms","start":"2026-08-13T00:50:40.239206Z","end":"2026-08-13T00:50:40.808162Z","steps":["trace[1348286618] 'process raft request' (duration: 390.10503ms)","trace[1348286618] 'compare' (duration: 178.024621ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:50:40.808301Z","caller":"traceutil/trace.go:171","msg":"trace[2064971109] linearizableReadLoop","detail":"{readStateIndex:2230; appliedIndex:2229; }","duration":"567.271716ms","start":"2026-08-13T00:50:40.240974Z","end":"2026-08-13T00:50:40.808245Z","steps":["trace[2064971109] 'read index received' (duration: 388.336988ms)","trace[2064971109] 'applied index is now lower than readState.Index' (duration: 178.932178ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:50:40.808406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:40.23919Z","time spent":"569.108429ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:50:40.808603Z","caller":"traceutil/trace.go:171","msg":"trace[1504330935] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"414.489232ms","start":"2026-08-13T00:50:40.394075Z","end":"2026-08-13T00:50:40.808564Z","steps":["trace[1504330935] 'process raft request' (duration: 413.558995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:40.80863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.613352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:50:40.808736Z","caller":"traceutil/trace.go:171","msg":"trace[591305950] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2143; }","duration":"567.749684ms","start":"2026-08-13T00:50:40.240957Z","end":"2026-08-13T00:50:40.808707Z","steps":["trace[591305950] 'agreement among raft nodes before linearized reading' (duration: 567.575751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:40.808743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.112759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-08-13T00:50:40.808763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.107112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T00:50:40.808794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:40.24095Z","time spent":"567.832706ms","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"info","ts":"2026-08-13T00:50:40.808797Z","caller":"traceutil/trace.go:171","msg":"trace[1235879720] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2143; }","duration":"564.175041ms","start":"2026-08-13T00:50:40.244608Z","end":"2026-08-13T00:50:40.808783Z","steps":["trace[1235879720] 'agreement among raft nodes before linearized reading' (duration: 563.987137ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:40.808825Z","caller":"traceutil/trace.go:171","msg":"trace[975081665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2143; }","duration":"557.154873ms","start":"2026-08-13T00:50:40.251633Z","end":"2026-08-13T00:50:40.808788Z","steps":["trace[975081665] 'agreement among raft nodes before linearized reading' (duration: 557.108082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:40.808851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:40.244601Z","time spent":"564.240552ms","remote":"127.0.0.1:34760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-13T00:50:40.8089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:40.251625Z","time spent":"557.265265ms","remote":"127.0.0.1:52672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T00:50:40.808808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:40.394049Z","time spent":"414.652054ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:50:40.808653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.274894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T00:50:40.809641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.493415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T00:50:40.809754Z","caller":"traceutil/trace.go:171","msg":"trace[471661779] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2143; }","duration":"312.616017ms","start":"2026-08-13T00:50:40.49709Z","end":"2026-08-13T00:50:40.809706Z","steps":["trace[471661779] 'agreement among raft nodes before linearized reading' (duration: 311.64343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:40.809826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:40.497073Z","time spent":"312.739849ms","remote":"127.0.0.1:57630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-08-13T00:50:40.809714Z","caller":"traceutil/trace.go:171","msg":"trace[1668065309] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2143; }","duration":"295.859554ms","start":"2026-08-13T00:50:40.513311Z","end":"2026-08-13T00:50:40.80917Z","steps":["trace[1668065309] 'agreement among raft nodes before linearized reading' (duration: 295.286363ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:41.03168Z","caller":"traceutil/trace.go:171","msg":"trace[1381531500] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"217.296928ms","start":"2026-08-13T00:50:40.814332Z","end":"2026-08-13T00:50:41.031629Z","steps":["trace[1381531500] 'process raft request' (duration: 159.025288ms)","trace[1381531500] 'compare' (duration: 57.987645ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:50:41.437892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.528266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-13T00:50:41.438037Z","caller":"traceutil/trace.go:171","msg":"trace[115677226] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2144; }","duration":"208.72715ms","start":"2026-08-13T00:50:41.229282Z","end":"2026-08-13T00:50:41.438009Z","steps":["trace[115677226] 'range keys from in-memory index tree' (duration: 208.286032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:41.438099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.994317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-13T00:50:41.438194Z","caller":"traceutil/trace.go:171","msg":"trace[200758046] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2144; }","duration":"296.176561ms","start":"2026-08-13T00:50:41.141994Z","end":"2026-08-13T00:50:41.438171Z","steps":["trace[200758046] 'range keys from in-memory index tree' (duration: 295.786593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:41.438206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.813694ms","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-13T00:50:41.43877Z","caller":"traceutil/trace.go:171","msg":"trace[226850384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2144; }","duration":"243.425435ms","start":"2026-08-13T00:50:41.195329Z","end":"2026-08-13T00:50:41.438755Z","steps":["trace[226850384] 'range keys from in-memory index tree' (duration: 242.560949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:41.946507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153701238623201672,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-13T00:50:41.984593Z","caller":"traceutil/trace.go:171","msg":"trace[38871048] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"540.795737ms","start":"2026-08-13T00:50:41.443764Z","end":"2026-08-13T00:50:41.98456Z","steps":["trace[38871048] 'process raft request' (duration: 540.598424ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:41.985379Z","caller":"traceutil/trace.go:171","msg":"trace[1393566880] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2232; }","duration":"539.365691ms","start":"2026-08-13T00:50:41.445984Z","end":"2026-08-13T00:50:41.985349Z","steps":["trace[1393566880] 'read index received' (duration: 538.536296ms)","trace[1393566880] 'applied index is now lower than readState.Index' (duration: 828.145µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:50:41.985525Z","caller":"traceutil/trace.go:171","msg":"trace[182179840] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"540.990701ms","start":"2026-08-13T00:50:41.444522Z","end":"2026-08-13T00:50:41.985513Z","steps":["trace[182179840] 'process raft request' (duration: 540.776417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:41.985526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:41.44368Z","time spent":"541.012542ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:50:41.985663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:41.444512Z","time spent":"541.054352ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:50:41.985628Z","caller":"traceutil/trace.go:171","msg":"trace[1698256387] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"541.536271ms","start":"2026-08-13T00:50:41.443887Z","end":"2026-08-13T00:50:41.985423Z","steps":["trace[1698256387] 'process raft request' (duration: 541.264886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:41.985913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:41.443859Z","time spent":"541.968029ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:50:41.986098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.069513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-13T00:50:41.986118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.030697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-08-13T00:50:41.986149Z","caller":"traceutil/trace.go:171","msg":"trace[475225144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2147; }","duration":"452.172235ms","start":"2026-08-13T00:50:41.533961Z","end":"2026-08-13T00:50:41.986133Z","steps":["trace[475225144] 'agreement among raft nodes before linearized reading' (duration: 452.024573ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:41.986176Z","caller":"traceutil/trace.go:171","msg":"trace[212451132] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2147; }","duration":"444.121108ms","start":"2026-08-13T00:50:41.542034Z","end":"2026-08-13T00:50:41.986155Z","steps":["trace[212451132] 'agreement among raft nodes before linearized reading' (duration: 443.960975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:41.986197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:41.53394Z","time spent":"452.249767ms","remote":"127.0.0.1:52920","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-13T00:50:41.986213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:41.542016Z","time spent":"444.19ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-13T00:50:41.985977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.986173ms","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-13T00:50:41.98631Z","caller":"traceutil/trace.go:171","msg":"trace[1462036372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2147; }","duration":"540.349559ms","start":"2026-08-13T00:50:41.44595Z","end":"2026-08-13T00:50:41.9863Z","steps":["trace[1462036372] 'agreement among raft nodes before linearized reading' (duration: 539.887431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:50:41.986339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:50:41.44594Z","time spent":"540.39171ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T00:50:53.466198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.366702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:50:53.466319Z","caller":"traceutil/trace.go:171","msg":"trace[1756925568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2205; }","duration":"213.557936ms","start":"2026-08-13T00:50:53.252733Z","end":"2026-08-13T00:50:53.466291Z","steps":["trace[1756925568] 'range keys from in-memory index tree' (duration: 213.234319ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:51:25.868388Z","caller":"traceutil/trace.go:171","msg":"trace[544416701] transaction","detail":"{read_only:false; response_revision:2408; number_of_response:1; }","duration":"168.689215ms","start":"2026-08-13T00:51:25.699663Z","end":"2026-08-13T00:51:25.868352Z","steps":["trace[544416701] 'process raft request' (duration: 103.498118ms)","trace[544416701] 'compare' (duration: 65.045383ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:51:25.885981Z","caller":"traceutil/trace.go:171","msg":"trace[459531546] transaction","detail":"{read_only:false; response_revision:2409; number_of_response:1; }","duration":"184.861473ms","start":"2026-08-13T00:51:25.701082Z","end":"2026-08-13T00:51:25.885943Z","steps":["trace[459531546] 'process raft request' (duration: 184.717499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:51:26.392604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.306287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-08-13T00:51:26.392642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.978202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T00:51:26.392777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.648329ms","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-13T00:51:26.3927Z","caller":"traceutil/trace.go:171","msg":"trace[213648067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2422; }","duration":"131.44607ms","start":"2026-08-13T00:51:26.261234Z","end":"2026-08-13T00:51:26.39268Z","steps":["trace[213648067] 'range keys from in-memory index tree' (duration: 131.145663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:51:26.392765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.92039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-13T00:51:26.392807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.446962ms","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-13T00:51:26.392814Z","caller":"traceutil/trace.go:171","msg":"trace[1463903751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2422; }","duration":"130.714371ms","start":"2026-08-13T00:51:26.26209Z","end":"2026-08-13T00:51:26.392805Z","steps":["trace[1463903751] 'range keys from in-memory index tree' (duration: 130.416233ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:51:26.392857Z","caller":"traceutil/trace.go:171","msg":"trace[554972570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2422; }","duration":"138.529894ms","start":"2026-08-13T00:51:26.254315Z","end":"2026-08-13T00:51:26.392845Z","steps":["trace[554972570] 'range keys from in-memory index tree' (duration: 138.310288ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:51:26.392773Z","caller":"traceutil/trace.go:171","msg":"trace[1148999021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2422; }","duration":"140.207377ms","start":"2026-08-13T00:51:26.252533Z","end":"2026-08-13T00:51:26.392741Z","steps":["trace[1148999021] 'range keys from in-memory index tree' (duration: 139.869029ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:51:26.39286Z","caller":"traceutil/trace.go:171","msg":"trace[1407310626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2422; }","duration":"120.090155ms","start":"2026-08-13T00:51:26.272748Z","end":"2026-08-13T00:51:26.392838Z","steps":["trace[1407310626] 'range keys from in-memory index tree' (duration: 119.028647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:51:26.392955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.987316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-08-13T00:51:26.392998Z","caller":"traceutil/trace.go:171","msg":"trace[1900542897] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2422; }","duration":"129.071838ms","start":"2026-08-13T00:51:26.263911Z","end":"2026-08-13T00:51:26.392983Z","steps":["trace[1900542897] 'range keys from in-memory index tree' (duration: 128.827022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:51:26.393294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.134406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-13T00:51:26.393391Z","caller":"traceutil/trace.go:171","msg":"trace[1871457139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2422; }","duration":"150.291761ms","start":"2026-08-13T00:51:26.243077Z","end":"2026-08-13T00:51:26.393369Z","steps":["trace[1871457139] 'range keys from in-memory index tree' (duration: 149.1434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:51:29.788006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.337173ms","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-13T00:51:29.78812Z","caller":"traceutil/trace.go:171","msg":"trace[1417674963] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2450; }","duration":"162.499727ms","start":"2026-08-13T00:51:29.625599Z","end":"2026-08-13T00:51:29.788098Z","steps":["trace[1417674963] 'count revisions from in-memory index tree' (duration: 162.216599ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:51:38.180575Z","caller":"traceutil/trace.go:171","msg":"trace[902873656] linearizableReadLoop","detail":"{readStateIndex:2645; appliedIndex:2644; }","duration":"333.197144ms","start":"2026-08-13T00:51:37.847339Z","end":"2026-08-13T00:51:38.180536Z","steps":["trace[902873656] 'read index received' (duration: 237.015354ms)","trace[902873656] 'applied index is now lower than readState.Index' (duration: 96.180159ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:51:38.180815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.446671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:51:38.180907Z","caller":"traceutil/trace.go:171","msg":"trace[422194141] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2544; }","duration":"333.628315ms","start":"2026-08-13T00:51:37.847261Z","end":"2026-08-13T00:51:38.18089Z","steps":["trace[422194141] 'agreement among raft nodes before linearized reading' (duration: 333.467791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:51:38.180981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:51:37.847232Z","time spent":"333.731638ms","remote":"127.0.0.1:49996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-08-13T00:52:12.085357Z","caller":"traceutil/trace.go:171","msg":"trace[501926211] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"357.356583ms","start":"2026-08-13T00:52:11.72797Z","end":"2026-08-13T00:52:12.085327Z","steps":["trace[501926211] 'process raft request' (duration: 357.128416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:12.085601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:11.727936Z","time spent":"357.543499ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:52:12.352398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.630373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-13T00:52:12.353314Z","caller":"traceutil/trace.go:171","msg":"trace[98260338] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:2735; }","duration":"126.582022ms","start":"2026-08-13T00:52:12.226674Z","end":"2026-08-13T00:52:12.353256Z","steps":["trace[98260338] 'range keys from in-memory index tree' (duration: 125.389555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:12.671025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:12.354553Z","time spent":"316.463961ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-13T00:52:13.20847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153701238623203169,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T00:52:13.626141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.314125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:52:13.626447Z","caller":"traceutil/trace.go:171","msg":"trace[748703568] linearizableReadLoop","detail":"{readStateIndex:2846; appliedIndex:2844; }","duration":"918.212139ms","start":"2026-08-13T00:52:12.708213Z","end":"2026-08-13T00:52:13.626425Z","steps":["trace[748703568] 'read index received' (duration: 505.44227ms)","trace[748703568] 'applied index is now lower than readState.Index' (duration: 412.768289ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:52:13.626737Z","caller":"traceutil/trace.go:171","msg":"trace[994468438] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"980.482934ms","start":"2026-08-13T00:52:12.646241Z","end":"2026-08-13T00:52:13.626724Z","steps":["trace[994468438] 'process raft request' (duration: 567.470917ms)","trace[994468438] 'compare' (duration: 412.084478ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:52:13.626807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:12.646203Z","time spent":"980.565396ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:52:13.626966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.716926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-08-13T00:52:13.627068Z","caller":"traceutil/trace.go:171","msg":"trace[1962568330] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2738; }","duration":"918.875861ms","start":"2026-08-13T00:52:12.708167Z","end":"2026-08-13T00:52:13.627043Z","steps":["trace[1962568330] 'agreement among raft nodes before linearized reading' (duration: 918.583822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:13.627129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:12.708153Z","time spent":"918.958573ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-13T00:52:13.627268Z","caller":"traceutil/trace.go:171","msg":"trace[1080382538] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"414.309008ms","start":"2026-08-13T00:52:13.212947Z","end":"2026-08-13T00:52:13.627257Z","steps":["trace[1080382538] 'process raft request' (duration: 413.42894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:13.62738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:13.21291Z","time spent":"414.39024ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:52:13.627549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.240573ms","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-13T00:52:13.627594Z","caller":"traceutil/trace.go:171","msg":"trace[512429544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2738; }","duration":"906.306104ms","start":"2026-08-13T00:52:12.721277Z","end":"2026-08-13T00:52:13.627583Z","steps":["trace[512429544] 'agreement among raft nodes before linearized reading' (duration: 906.18837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:13.62763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:12.721265Z","time spent":"906.357996ms","remote":"127.0.0.1:52920","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":"warn","ts":"2026-08-13T00:52:13.627649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.528817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-13T00:52:13.627765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.885492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:52:13.627795Z","caller":"traceutil/trace.go:171","msg":"trace[1620502252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2738; }","duration":"375.963035ms","start":"2026-08-13T00:52:13.251823Z","end":"2026-08-13T00:52:13.627786Z","steps":["trace[1620502252] 'agreement among raft nodes before linearized reading' (duration: 375.915533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:13.627828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:13.251795Z","time spent":"376.027237ms","remote":"127.0.0.1:52672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T00:52:13.627902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.055173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-13T00:52:13.627955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.541921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-08-13T00:52:13.62799Z","caller":"traceutil/trace.go:171","msg":"trace[556633342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2738; }","duration":"917.177147ms","start":"2026-08-13T00:52:12.710792Z","end":"2026-08-13T00:52:13.62797Z","steps":["trace[556633342] 'agreement among raft nodes before linearized reading' (duration: 916.781855ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:13.628007Z","caller":"traceutil/trace.go:171","msg":"trace[17831646] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2738; }","duration":"332.643114ms","start":"2026-08-13T00:52:13.295351Z","end":"2026-08-13T00:52:13.627994Z","steps":["trace[17831646] 'agreement among raft nodes before linearized reading' (duration: 332.52018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:13.628046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:12.710783Z","time spent":"917.25378ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-13T00:52:13.627798Z","caller":"traceutil/trace.go:171","msg":"trace[554477737] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2738; }","duration":"342.732593ms","start":"2026-08-13T00:52:13.285037Z","end":"2026-08-13T00:52:13.62777Z","steps":["trace[554477737] 'agreement among raft nodes before linearized reading' (duration: 342.357431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:13.627966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.614364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-13T00:52:13.628236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.89042ms","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-13T00:52:13.628294Z","caller":"traceutil/trace.go:171","msg":"trace[1044232767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2738; }","duration":"912.002844ms","start":"2026-08-13T00:52:12.716277Z","end":"2026-08-13T00:52:13.628279Z","steps":["trace[1044232767] 'agreement among raft nodes before linearized reading' (duration: 911.86261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:13.628254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.493245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-13T00:52:13.628284Z","caller":"traceutil/trace.go:171","msg":"trace[604827418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2738; }","duration":"906.963795ms","start":"2026-08-13T00:52:12.721256Z","end":"2026-08-13T00:52:13.62822Z","steps":["trace[604827418] 'agreement among raft nodes before linearized reading' (duration: 906.260023ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:13.628458Z","caller":"traceutil/trace.go:171","msg":"trace[1083436882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2738; }","duration":"903.741634ms","start":"2026-08-13T00:52:12.724692Z","end":"2026-08-13T00:52:13.628434Z","steps":["trace[1083436882] 'agreement among raft nodes before linearized reading' (duration: 903.267449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:13.628583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:12.724666Z","time spent":"903.894318ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T00:52:13.628582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:12.721244Z","time spent":"907.238284ms","remote":"127.0.0.1:52920","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-13T00:52:13.628329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:12.716223Z","time spent":"912.098597ms","remote":"127.0.0.1:52920","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":"warn","ts":"2026-08-13T00:52:13.62807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:13.295326Z","time spent":"332.733907ms","remote":"127.0.0.1:52854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1389,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-13T00:52:13.628156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:13.285012Z","time spent":"343.139016ms","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-13T00:52:13.629739Z","caller":"traceutil/trace.go:171","msg":"trace[157788403] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"957.336294ms","start":"2026-08-13T00:52:12.67239Z","end":"2026-08-13T00:52:13.629726Z","steps":["trace[157788403] 'process raft request' (duration: 953.877654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:13.630246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:12.672365Z","time spent":"957.457118ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:56:06.640615Z","caller":"traceutil/trace.go:171","msg":"trace[210864821] transaction","detail":"{read_only:false; response_revision:4548; number_of_response:1; }","duration":"100.545605ms","start":"2026-08-13T00:56:06.540042Z","end":"2026-08-13T00:56:06.640587Z","steps":["trace[210864821] 'process raft request' (duration: 100.390578ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:07.051272Z","caller":"traceutil/trace.go:171","msg":"trace[572294454] linearizableReadLoop","detail":"{readStateIndex:4733; appliedIndex:4731; }","duration":"151.516277ms","start":"2026-08-13T00:56:06.899729Z","end":"2026-08-13T00:56:07.051246Z","steps":["trace[572294454] 'read index received' (duration: 29.275926ms)","trace[572294454] 'applied index is now lower than readState.Index' (duration: 122.239601ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:56:07.051472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.729116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-13T00:56:07.051503Z","caller":"traceutil/trace.go:171","msg":"trace[571831242] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4551; }","duration":"151.82691ms","start":"2026-08-13T00:56:06.899665Z","end":"2026-08-13T00:56:07.051492Z","steps":["trace[571831242] 'agreement among raft nodes before linearized reading' (duration: 151.689775ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:07.051372Z","caller":"traceutil/trace.go:171","msg":"trace[68334249] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"164.499225ms","start":"2026-08-13T00:56:06.88684Z","end":"2026-08-13T00:56:07.051339Z","steps":["trace[68334249] 'process raft request' (duration: 164.292616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:07.05167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.462874ms","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-13T00:56:07.05172Z","caller":"traceutil/trace.go:171","msg":"trace[1830507347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4551; }","duration":"146.535387ms","start":"2026-08-13T00:56:06.905176Z","end":"2026-08-13T00:56:07.051711Z","steps":["trace[1830507347] 'agreement among raft nodes before linearized reading' (duration: 146.427032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:07.051723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.461045ms","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-13T00:56:07.051786Z","caller":"traceutil/trace.go:171","msg":"trace[1622979173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4551; }","duration":"151.57998ms","start":"2026-08-13T00:56:06.900189Z","end":"2026-08-13T00:56:07.051769Z","steps":["trace[1622979173] 'agreement among raft nodes before linearized reading' (duration: 151.358471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:07.051888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.584599ms","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-13T00:56:07.051992Z","caller":"traceutil/trace.go:171","msg":"trace[730985298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4551; }","duration":"146.743016ms","start":"2026-08-13T00:56:06.90523Z","end":"2026-08-13T00:56:07.051973Z","steps":["trace[730985298] 'agreement among raft nodes before linearized reading' (duration: 146.355399ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:10.755741Z","caller":"traceutil/trace.go:171","msg":"trace[1125539314] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"106.779985ms","start":"2026-08-13T00:56:10.64893Z","end":"2026-08-13T00:56:10.75571Z","steps":["trace[1125539314] 'process raft request' (duration: 106.583167ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:13.828474Z","caller":"traceutil/trace.go:171","msg":"trace[1576242382] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"361.848856ms","start":"2026-08-13T00:56:13.466597Z","end":"2026-08-13T00:56:13.828446Z","steps":["trace[1576242382] 'process raft request' (duration: 361.69657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:13.828643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:13.466589Z","time spent":"361.959861ms","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:56:13.842643Z","caller":"traceutil/trace.go:171","msg":"trace[1253491381] linearizableReadLoop","detail":"{readStateIndex:4950; appliedIndex:4948; }","duration":"142.960291ms","start":"2026-08-13T00:56:13.699671Z","end":"2026-08-13T00:56:13.842632Z","steps":["trace[1253491381] 'read index received' (duration: 128.644025ms)","trace[1253491381] 'applied index is now lower than readState.Index' (duration: 14.315566ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:13.842671Z","caller":"traceutil/trace.go:171","msg":"trace[546482756] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"374.525883ms","start":"2026-08-13T00:56:13.468124Z","end":"2026-08-13T00:56:13.84265Z","steps":["trace[546482756] 'process raft request' (duration: 374.44775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:13.842747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.078547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-25271a9f-e034-452d-bcf4-17f00f9def80\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-08-13T00:56:13.842773Z","caller":"traceutil/trace.go:171","msg":"trace[644506580] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-25271a9f-e034-452d-bcf4-17f00f9def80; range_end:; response_count:1; response_revision:4764; }","duration":"143.144339ms","start":"2026-08-13T00:56:13.699621Z","end":"2026-08-13T00:56:13.842765Z","steps":["trace[644506580] 'agreement among raft nodes before linearized reading' (duration: 143.074856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:13.842775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:13.468103Z","time spent":"374.623167ms","remote":"127.0.0.1:52746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T00:56:14.057833Z","caller":"traceutil/trace.go:171","msg":"trace[699642404] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"122.59144ms","start":"2026-08-13T00:56:13.935215Z","end":"2026-08-13T00:56:14.057807Z","steps":["trace[699642404] 'process raft request' (duration: 97.434095ms)","trace[699642404] 'compare' (duration: 24.978528ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:14.080662Z","caller":"traceutil/trace.go:171","msg":"trace[963743632] linearizableReadLoop","detail":"{readStateIndex:4953; appliedIndex:4951; }","duration":"144.817401ms","start":"2026-08-13T00:56:13.935823Z","end":"2026-08-13T00:56:14.08064Z","steps":["trace[963743632] 'read index received' (duration: 96.706344ms)","trace[963743632] 'applied index is now lower than readState.Index' (duration: 48.110087ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:14.080771Z","caller":"traceutil/trace.go:171","msg":"trace[1151586210] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"145.109363ms","start":"2026-08-13T00:56:13.935635Z","end":"2026-08-13T00:56:14.080745Z","steps":["trace[1151586210] 'process raft request' (duration: 144.894594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:14.080957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.120123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-7f665d466b-5pzqq\" ","response":"range_response_count:1 size:11845"} {"level":"info","ts":"2026-08-13T00:56:14.081049Z","caller":"traceutil/trace.go:171","msg":"trace[218821014] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-7f665d466b-5pzqq; range_end:; response_count:1; response_revision:4767; }","duration":"145.210177ms","start":"2026-08-13T00:56:13.935794Z","end":"2026-08-13T00:56:14.081004Z","steps":["trace[218821014] 'agreement among raft nodes before linearized reading' (duration: 144.957207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:29.034523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.759826133s","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-13T00:56:29.03461Z","caller":"traceutil/trace.go:171","msg":"trace[373617152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4944; }","duration":"1.759963169s","start":"2026-08-13T00:56:27.274631Z","end":"2026-08-13T00:56:29.034594Z","steps":["trace[373617152] 'range keys from in-memory index tree' (duration: 1.759642635s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:29.034649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:27.274611Z","time spent":"1.760029382s","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T00:56:29.034824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925372973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-13T00:56:29.034842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.559532565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-08-13T00:56:29.034895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.763232077s","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-13T00:56:29.034904Z","caller":"traceutil/trace.go:171","msg":"trace[380942658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4944; }","duration":"1.925507548s","start":"2026-08-13T00:56:27.109378Z","end":"2026-08-13T00:56:29.034886Z","steps":["trace[380942658] 'range keys from in-memory index tree' (duration: 1.925191436s)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:29.034922Z","caller":"traceutil/trace.go:171","msg":"trace[867243155] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4944; }","duration":"1.55965352s","start":"2026-08-13T00:56:27.475248Z","end":"2026-08-13T00:56:29.034902Z","steps":["trace[867243155] 'range keys from in-memory index tree' (duration: 1.559338247s)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:29.034927Z","caller":"traceutil/trace.go:171","msg":"trace[781161290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4944; }","duration":"1.763301291s","start":"2026-08-13T00:56:27.271616Z","end":"2026-08-13T00:56:29.034917Z","steps":["trace[781161290] 'range keys from in-memory index tree' (duration: 1.763077352s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:29.034965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:27.475231Z","time spent":"1.559724043s","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-13T00:56:29.034957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:27.109359Z","time spent":"1.925585742s","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T00:56:29.035164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.901893325s","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-13T00:56:29.035205Z","caller":"traceutil/trace.go:171","msg":"trace[375933021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4944; }","duration":"1.901971578s","start":"2026-08-13T00:56:27.133223Z","end":"2026-08-13T00:56:29.035194Z","steps":["trace[375933021] 'range keys from in-memory index tree' (duration: 1.901764159s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:29.035239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:27.133199Z","time spent":"1.90203332s","remote":"127.0.0.1:52920","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-13T00:56:29.035148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.760191108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-13T00:56:29.034981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:27.271597Z","time spent":"1.763377224s","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T00:56:29.035496Z","caller":"traceutil/trace.go:171","msg":"trace[1633197835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4944; }","duration":"1.760482351s","start":"2026-08-13T00:56:27.274908Z","end":"2026-08-13T00:56:29.03539Z","steps":["trace[1633197835] 'range keys from in-memory index tree' (duration: 1.759818462s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:29.035724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:27.274889Z","time spent":"1.760793024s","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T00:56:29.035845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.767191276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-08-13T00:56:29.035912Z","caller":"traceutil/trace.go:171","msg":"trace[63977409] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4944; }","duration":"1.767263749s","start":"2026-08-13T00:56:27.268633Z","end":"2026-08-13T00:56:29.035897Z","steps":["trace[63977409] 'range keys from in-memory index tree' (duration: 1.76611261s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:29.035499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635767377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"warn","ts":"2026-08-13T00:56:29.035948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:27.268608Z","time spent":"1.767330852s","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-13T00:56:29.035995Z","caller":"traceutil/trace.go:171","msg":"trace[1243821293] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4944; }","duration":"1.63630869s","start":"2026-08-13T00:56:27.399659Z","end":"2026-08-13T00:56:29.035968Z","steps":["trace[1243821293] 'range keys from in-memory index tree' (duration: 1.635480915s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:29.036053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:27.399646Z","time spent":"1.636392153s","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-08-13T00:56:29.231165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.011295ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-13T00:56:29.231413Z","caller":"traceutil/trace.go:171","msg":"trace[1395733527] linearizableReadLoop","detail":"{readStateIndex:5136; appliedIndex:5135; }","duration":"979.994091ms","start":"2026-08-13T00:56:28.251395Z","end":"2026-08-13T00:56:29.231389Z","steps":["trace[1395733527] 'read index received' (duration: 112.694864ms)","trace[1395733527] 'applied index is now lower than readState.Index' (duration: 867.297067ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:56:29.231591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.148218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:56:29.231718Z","caller":"traceutil/trace.go:171","msg":"trace[677827452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4944; }","duration":"980.366047ms","start":"2026-08-13T00:56:28.251331Z","end":"2026-08-13T00:56:29.231697Z","steps":["trace[677827452] 'agreement among raft nodes before linearized reading' (duration: 980.146348ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:29.231721Z","caller":"traceutil/trace.go:171","msg":"trace[1865983933] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"189.984684ms","start":"2026-08-13T00:56:29.04169Z","end":"2026-08-13T00:56:29.231675Z","steps":["trace[1865983933] 'process raft request' (duration: 189.945753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:29.231856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:28.251311Z","time spent":"980.497773ms","remote":"127.0.0.1:52672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T00:56:29.231921Z","caller":"traceutil/trace.go:171","msg":"trace[2023627651] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"456.779184ms","start":"2026-08-13T00:56:28.775125Z","end":"2026-08-13T00:56:29.231904Z","steps":["trace[2023627651] 'process raft request' (duration: 456.138887ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:29.231979Z","caller":"traceutil/trace.go:171","msg":"trace[1956911253] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"429.083506ms","start":"2026-08-13T00:56:28.802863Z","end":"2026-08-13T00:56:29.231946Z","steps":["trace[1956911253] 'process raft request' (duration: 428.71821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:29.232103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:28.775096Z","time spent":"456.895829ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:56:29.232188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:28.802839Z","time spent":"429.241133ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:56:29.277377Z","caller":"traceutil/trace.go:171","msg":"trace[396436745] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"235.564175ms","start":"2026-08-13T00:56:29.041796Z","end":"2026-08-13T00:56:29.27736Z","steps":["trace[396436745] 'process raft request' (duration: 235.45379ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:29.277416Z","caller":"traceutil/trace.go:171","msg":"trace[1680333489] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"235.642758ms","start":"2026-08-13T00:56:29.041741Z","end":"2026-08-13T00:56:29.277384Z","steps":["trace[1680333489] 'process raft request' (duration: 235.389247ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:29.277751Z","caller":"traceutil/trace.go:171","msg":"trace[291522769] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"235.908519ms","start":"2026-08-13T00:56:29.041825Z","end":"2026-08-13T00:56:29.277733Z","steps":["trace[291522769] 'process raft request' (duration: 235.48176ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:29.277865Z","caller":"traceutil/trace.go:171","msg":"trace[1055021652] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"235.926569ms","start":"2026-08-13T00:56:29.041918Z","end":"2026-08-13T00:56:29.277845Z","steps":["trace[1055021652] 'process raft request' (duration: 235.489491ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:29.277946Z","caller":"traceutil/trace.go:171","msg":"trace[1032990529] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"235.954741ms","start":"2026-08-13T00:56:29.041981Z","end":"2026-08-13T00:56:29.277936Z","steps":["trace[1032990529] 'process raft request' (duration: 235.720401ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:29.278084Z","caller":"traceutil/trace.go:171","msg":"trace[1492592697] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"235.735731ms","start":"2026-08-13T00:56:29.042038Z","end":"2026-08-13T00:56:29.277774Z","steps":["trace[1492592697] 'process raft request' (duration: 235.69814ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:29.324808Z","caller":"traceutil/trace.go:171","msg":"trace[954508922] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"142.203201ms","start":"2026-08-13T00:56:29.18259Z","end":"2026-08-13T00:56:29.324793Z","steps":["trace[954508922] 'process raft request' (duration: 142.092476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:29.324966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.551859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-08-13T00:56:29.325012Z","caller":"traceutil/trace.go:171","msg":"trace[963403118] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4954; }","duration":"147.648993ms","start":"2026-08-13T00:56:29.177351Z","end":"2026-08-13T00:56:29.325Z","steps":["trace[963403118] 'agreement among raft nodes before linearized reading' (duration: 147.457265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:29.325259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048228756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-13T00:56:29.325292Z","caller":"traceutil/trace.go:171","msg":"trace[1423061244] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4954; }","duration":"1.048285558s","start":"2026-08-13T00:56:28.276998Z","end":"2026-08-13T00:56:29.325284Z","steps":["trace[1423061244] 'agreement among raft nodes before linearized reading' (duration: 1.048212115s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:29.325311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:28.276988Z","time spent":"1.04831852s","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-13T00:56:29.437183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.969676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:56:29.437258Z","caller":"traceutil/trace.go:171","msg":"trace[1258061489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4954; }","duration":"105.109633ms","start":"2026-08-13T00:56:29.332131Z","end":"2026-08-13T00:56:29.437241Z","steps":["trace[1258061489] 'range keys from in-memory index tree' (duration: 104.894683ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:29.465957Z","caller":"traceutil/trace.go:171","msg":"trace[722265634] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"129.325113ms","start":"2026-08-13T00:56:29.336602Z","end":"2026-08-13T00:56:29.465927Z","steps":["trace[722265634] 'process raft request' (duration: 129.02247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:30.032252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.242993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:56:30.03245Z","caller":"traceutil/trace.go:171","msg":"trace[1178486993] linearizableReadLoop","detail":"{readStateIndex:5153; appliedIndex:5152; }","duration":"201.539687ms","start":"2026-08-13T00:56:29.830893Z","end":"2026-08-13T00:56:30.032432Z","steps":["trace[1178486993] 'read index received' (duration: 40.050694ms)","trace[1178486993] 'applied index is now lower than readState.Index' (duration: 161.487353ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:56:30.032639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.739805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-08-13T00:56:30.032714Z","caller":"traceutil/trace.go:171","msg":"trace[192798776] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4961; }","duration":"201.830709ms","start":"2026-08-13T00:56:29.830872Z","end":"2026-08-13T00:56:30.032703Z","steps":["trace[192798776] 'agreement among raft nodes before linearized reading' (duration: 201.643882ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:30.032611Z","caller":"traceutil/trace.go:171","msg":"trace[1960340331] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"211.685668ms","start":"2026-08-13T00:56:29.820895Z","end":"2026-08-13T00:56:30.03258Z","steps":["trace[1960340331] 'process raft request' (duration: 50.033329ms)","trace[1960340331] 'compare' (duration: 161.140668ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:30.288751Z","caller":"traceutil/trace.go:171","msg":"trace[128607359] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"249.084923ms","start":"2026-08-13T00:56:30.039643Z","end":"2026-08-13T00:56:30.288728Z","steps":["trace[128607359] 'process raft request' (duration: 170.752288ms)","trace[128607359] 'compare' (duration: 77.949988ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:35.449895Z","caller":"traceutil/trace.go:171","msg":"trace[1663902555] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"106.430607ms","start":"2026-08-13T00:56:35.343443Z","end":"2026-08-13T00:56:35.449873Z","steps":["trace[1663902555] 'process raft request' (duration: 106.390596ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:35.449925Z","caller":"traceutil/trace.go:171","msg":"trace[80879437] linearizableReadLoop","detail":"{readStateIndex:5194; appliedIndex:5193; }","duration":"106.698359ms","start":"2026-08-13T00:56:35.343184Z","end":"2026-08-13T00:56:35.449882Z","steps":["trace[80879437] 'read index received' (duration: 58.253764ms)","trace[80879437] 'applied index is now lower than readState.Index' (duration: 48.441035ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:35.449983Z","caller":"traceutil/trace.go:171","msg":"trace[2077145867] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"106.693928ms","start":"2026-08-13T00:56:35.343267Z","end":"2026-08-13T00:56:35.449961Z","steps":["trace[2077145867] 'process raft request' (duration: 106.49598ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:35.450242Z","caller":"traceutil/trace.go:171","msg":"trace[914000246] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"106.911058ms","start":"2026-08-13T00:56:35.343318Z","end":"2026-08-13T00:56:35.450229Z","steps":["trace[914000246] 'process raft request' (duration: 106.479709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:35.450274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.069314ms","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-13T00:56:35.450343Z","caller":"traceutil/trace.go:171","msg":"trace[113320854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5005; }","duration":"107.175479ms","start":"2026-08-13T00:56:35.343155Z","end":"2026-08-13T00:56:35.450331Z","steps":["trace[113320854] 'agreement among raft nodes before linearized reading' (duration: 106.828594ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:35.450429Z","caller":"traceutil/trace.go:171","msg":"trace[949864972] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"107.168078ms","start":"2026-08-13T00:56:35.343242Z","end":"2026-08-13T00:56:35.45041Z","steps":["trace[949864972] 'process raft request' (duration: 106.482229ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:35.450533Z","caller":"traceutil/trace.go:171","msg":"trace[390303316] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"110.294292ms","start":"2026-08-13T00:56:35.340225Z","end":"2026-08-13T00:56:35.450519Z","steps":["trace[390303316] 'process raft request' (duration: 61.134696ms)","trace[390303316] 'compare' (duration: 48.223736ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:57:09.370858Z","caller":"traceutil/trace.go:171","msg":"trace[1634363890] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"112.675175ms","start":"2026-08-13T00:57:09.258165Z","end":"2026-08-13T00:57:09.37084Z","steps":["trace[1634363890] 'process raft request' (duration: 112.559941ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:09.410833Z","caller":"traceutil/trace.go:171","msg":"trace[1538738192] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"108.897494ms","start":"2026-08-13T00:57:09.301922Z","end":"2026-08-13T00:57:09.410819Z","steps":["trace[1538738192] 'process raft request' (duration: 108.689335ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:21.64161Z","caller":"traceutil/trace.go:171","msg":"trace[1413524395] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"116.556796ms","start":"2026-08-13T00:57:21.525031Z","end":"2026-08-13T00:57:21.641588Z","steps":["trace[1413524395] 'process raft request' (duration: 116.40891ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:21.64608Z","caller":"traceutil/trace.go:171","msg":"trace[1249587130] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"103.248982ms","start":"2026-08-13T00:57:21.542784Z","end":"2026-08-13T00:57:21.646032Z","steps":["trace[1249587130] 'process raft request' (duration: 103.146538ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:29.265519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2820} {"level":"info","ts":"2026-08-13T00:57:29.355256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2820,"took":"88.849018ms","hash":1565487552,"current-db-size-bytes":53895168,"current-db-size":"54 MB","current-db-size-in-use-bytes":29126656,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-13T00:57:29.355319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1565487552,"revision":2820,"compact-revision":-1} {"level":"info","ts":"2026-08-13T00:57:37.887788Z","caller":"traceutil/trace.go:171","msg":"trace[451680921] linearizableReadLoop","detail":"{readStateIndex:5728; appliedIndex:5726; }","duration":"250.409061ms","start":"2026-08-13T00:57:37.637357Z","end":"2026-08-13T00:57:37.887766Z","steps":["trace[451680921] 'read index received' (duration: 72.329448ms)","trace[451680921] 'applied index is now lower than readState.Index' (duration: 178.078573ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:57:37.887813Z","caller":"traceutil/trace.go:171","msg":"trace[2028738080] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"193.630865ms","start":"2026-08-13T00:57:37.694148Z","end":"2026-08-13T00:57:37.887779Z","steps":["trace[2028738080] 'process raft request' (duration: 193.564992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:37.887956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.583989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T00:57:37.888213Z","caller":"traceutil/trace.go:171","msg":"trace[1337990046] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5520; }","duration":"250.887101ms","start":"2026-08-13T00:57:37.637304Z","end":"2026-08-13T00:57:37.888191Z","steps":["trace[1337990046] 'agreement among raft nodes before linearized reading' (duration: 250.575878ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:37.887912Z","caller":"traceutil/trace.go:171","msg":"trace[902202548] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"254.423724ms","start":"2026-08-13T00:57:37.633462Z","end":"2026-08-13T00:57:37.887886Z","steps":["trace[902202548] 'process raft request' (duration: 213.112187ms)","trace[902202548] 'compare' (duration: 41.017204ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:57:37.901846Z","caller":"traceutil/trace.go:171","msg":"trace[805810199] transaction","detail":"{read_only:false; response_revision:5521; number_of_response:1; }","duration":"174.394903ms","start":"2026-08-13T00:57:37.727428Z","end":"2026-08-13T00:57:37.901823Z","steps":["trace[805810199] 'process raft request' (duration: 174.125011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:37.901895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.076247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-13T00:57:37.901955Z","caller":"traceutil/trace.go:171","msg":"trace[808041859] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5521; }","duration":"161.157591ms","start":"2026-08-13T00:57:37.74078Z","end":"2026-08-13T00:57:37.901938Z","steps":["trace[808041859] 'agreement among raft nodes before linearized reading' (duration: 160.952492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:37.901966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.32847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8317"} {"level":"info","ts":"2026-08-13T00:57:37.902009Z","caller":"traceutil/trace.go:171","msg":"trace[1127037148] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:5521; }","duration":"174.406754ms","start":"2026-08-13T00:57:37.727592Z","end":"2026-08-13T00:57:37.901999Z","steps":["trace[1127037148] 'agreement among raft nodes before linearized reading' (duration: 174.32182ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:53.850203Z","caller":"traceutil/trace.go:171","msg":"trace[1046928426] transaction","detail":"{read_only:false; response_revision:5631; number_of_response:1; }","duration":"103.097131ms","start":"2026-08-13T00:57:53.747087Z","end":"2026-08-13T00:57:53.850184Z","steps":["trace[1046928426] 'process raft request' (duration: 102.975896ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:54.287103Z","caller":"traceutil/trace.go:171","msg":"trace[1826786605] transaction","detail":"{read_only:false; response_revision:5639; number_of_response:1; }","duration":"111.881553ms","start":"2026-08-13T00:57:54.175197Z","end":"2026-08-13T00:57:54.287079Z","steps":["trace[1826786605] 'process raft request' (duration: 111.738397ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:54.331346Z","caller":"traceutil/trace.go:171","msg":"trace[21268024] linearizableReadLoop","detail":"{readStateIndex:5852; appliedIndex:5850; }","duration":"151.801654ms","start":"2026-08-13T00:57:54.179516Z","end":"2026-08-13T00:57:54.331318Z","steps":["trace[21268024] 'read index received' (duration: 107.456491ms)","trace[21268024] 'applied index is now lower than readState.Index' (duration: 44.344013ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:57:54.331446Z","caller":"traceutil/trace.go:171","msg":"trace[1808105692] transaction","detail":"{read_only:false; response_revision:5640; number_of_response:1; }","duration":"155.246665ms","start":"2026-08-13T00:57:54.176172Z","end":"2026-08-13T00:57:54.331419Z","steps":["trace[1808105692] 'process raft request' (duration: 154.975863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:54.331527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.991133ms","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-13T00:57:54.331569Z","caller":"traceutil/trace.go:171","msg":"trace[1032771564] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5640; }","duration":"152.075557ms","start":"2026-08-13T00:57:54.179482Z","end":"2026-08-13T00:57:54.331557Z","steps":["trace[1032771564] 'agreement among raft nodes before linearized reading' (duration: 151.982473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:54.331563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.672379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:57:54.331613Z","caller":"traceutil/trace.go:171","msg":"trace[1967839705] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5640; }","duration":"145.742082ms","start":"2026-08-13T00:57:54.185858Z","end":"2026-08-13T00:57:54.3316Z","steps":["trace[1967839705] 'agreement among raft nodes before linearized reading' (duration: 145.660228ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:59:57.064735Z","caller":"traceutil/trace.go:171","msg":"trace[1479950821] transaction","detail":"{read_only:false; response_revision:6484; number_of_response:1; }","duration":"155.576577ms","start":"2026-08-13T00:59:56.909128Z","end":"2026-08-13T00:59:57.064705Z","steps":["trace[1479950821] 'process raft request' (duration: 155.411993ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:00:19.183885Z","caller":"traceutil/trace.go:171","msg":"trace[62175453] transaction","detail":"{read_only:false; response_revision:6652; number_of_response:1; }","duration":"112.534919ms","start":"2026-08-13T01:00:19.071325Z","end":"2026-08-13T01:00:19.18386Z","steps":["trace[62175453] 'process raft request' (duration: 28.178121ms)","trace[62175453] 'compare' (duration: 83.776343ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:00:27.395088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.723907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:00:27.395171Z","caller":"traceutil/trace.go:171","msg":"trace[445293191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6693; }","duration":"145.866051ms","start":"2026-08-13T01:00:27.249289Z","end":"2026-08-13T01:00:27.395155Z","steps":["trace[445293191] 'range keys from in-memory index tree' (duration: 145.627085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:00:27.395449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.728343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:00:27.395474Z","caller":"traceutil/trace.go:171","msg":"trace[1130369057] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:6693; }","duration":"258.782864ms","start":"2026-08-13T01:00:27.136681Z","end":"2026-08-13T01:00:27.395464Z","steps":["trace[1130369057] 'count revisions from in-memory index tree' (duration: 258.620931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:00:27.395846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.314202ms","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-13T01:00:27.395867Z","caller":"traceutil/trace.go:171","msg":"trace[260157907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6693; }","duration":"154.374654ms","start":"2026-08-13T01:00:27.241488Z","end":"2026-08-13T01:00:27.395863Z","steps":["trace[260157907] 'range keys from in-memory index tree' (duration: 154.163908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:00:27.396017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.846506ms","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-13T01:00:27.39604Z","caller":"traceutil/trace.go:171","msg":"trace[1611549198] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6693; }","duration":"149.895436ms","start":"2026-08-13T01:00:27.246136Z","end":"2026-08-13T01:00:27.396031Z","steps":["trace[1611549198] 'range keys from in-memory index tree' (duration: 149.735042ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:00:27.544717Z","caller":"traceutil/trace.go:171","msg":"trace[315038524] transaction","detail":"{read_only:false; response_revision:6694; number_of_response:1; }","duration":"145.071401ms","start":"2026-08-13T01:00:27.399624Z","end":"2026-08-13T01:00:27.544695Z","steps":["trace[315038524] 'process raft request' (duration: 144.946218ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:00:27.56344Z","caller":"traceutil/trace.go:171","msg":"trace[13636942] transaction","detail":"{read_only:false; response_revision:6695; number_of_response:1; }","duration":"163.403829ms","start":"2026-08-13T01:00:27.400009Z","end":"2026-08-13T01:00:27.563413Z","steps":["trace[13636942] 'process raft request' (duration: 163.190053ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:00:27.563456Z","caller":"traceutil/trace.go:171","msg":"trace[1515924658] transaction","detail":"{read_only:false; response_revision:6696; number_of_response:1; }","duration":"159.99072ms","start":"2026-08-13T01:00:27.403433Z","end":"2026-08-13T01:00:27.563423Z","steps":["trace[1515924658] 'process raft request' (duration: 159.889427ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:00:39.30984Z","caller":"traceutil/trace.go:171","msg":"trace[310682089] transaction","detail":"{read_only:false; response_revision:6755; number_of_response:1; }","duration":"429.957046ms","start":"2026-08-13T01:00:38.879861Z","end":"2026-08-13T01:00:39.309818Z","steps":["trace[310682089] 'process raft request' (duration: 429.690409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:00:39.310114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:00:38.87985Z","time spent":"430.074479ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:00:39.310789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.498631ms","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-13T01:00:39.310846Z","caller":"traceutil/trace.go:171","msg":"trace[992494063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6755; }","duration":"430.561222ms","start":"2026-08-13T01:00:38.880266Z","end":"2026-08-13T01:00:39.310827Z","steps":["trace[992494063] 'agreement among raft nodes before linearized reading' (duration: 430.424159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:00:39.311048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:00:38.880261Z","time spent":"430.769848ms","remote":"127.0.0.1:52920","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":"info","ts":"2026-08-13T01:00:39.311572Z","caller":"traceutil/trace.go:171","msg":"trace[556466289] linearizableReadLoop","detail":"{readStateIndex:7009; appliedIndex:7008; }","duration":"429.505694ms","start":"2026-08-13T01:00:38.88028Z","end":"2026-08-13T01:00:39.309786Z","steps":["trace[556466289] 'read index received' (duration: 429.304579ms)","trace[556466289] 'applied index is now lower than readState.Index' (duration: 200.075µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:00:39.357487Z","caller":"traceutil/trace.go:171","msg":"trace[764695015] transaction","detail":"{read_only:false; response_revision:6756; number_of_response:1; }","duration":"475.634758ms","start":"2026-08-13T01:00:38.881835Z","end":"2026-08-13T01:00:39.35747Z","steps":["trace[764695015] 'process raft request' (duration: 475.542596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:00:39.357729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:00:38.88181Z","time spent":"475.728261ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:00:39.358112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.293258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:00:39.358141Z","caller":"traceutil/trace.go:171","msg":"trace[1348332249] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6756; }","duration":"445.322759ms","start":"2026-08-13T01:00:38.912811Z","end":"2026-08-13T01:00:39.358134Z","steps":["trace[1348332249] 'agreement among raft nodes before linearized reading' (duration: 445.272068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:00:39.358189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:00:38.912762Z","time spent":"445.419732ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-13T01:00:39.358331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.094235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:00:39.358354Z","caller":"traceutil/trace.go:171","msg":"trace[411599599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6756; }","duration":"107.145237ms","start":"2026-08-13T01:00:39.251203Z","end":"2026-08-13T01:00:39.358349Z","steps":["trace[411599599] 'agreement among raft nodes before linearized reading' (duration: 107.107436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:00:39.358592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.636965ms","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-13T01:00:39.35865Z","caller":"traceutil/trace.go:171","msg":"trace[1513735339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6756; }","duration":"161.718507ms","start":"2026-08-13T01:00:39.196896Z","end":"2026-08-13T01:00:39.358614Z","steps":["trace[1513735339] 'agreement among raft nodes before linearized reading' (duration: 161.626114ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:00:39.425176Z","caller":"traceutil/trace.go:171","msg":"trace[954083406] transaction","detail":"{read_only:false; response_revision:6757; number_of_response:1; }","duration":"109.541381ms","start":"2026-08-13T01:00:39.315614Z","end":"2026-08-13T01:00:39.425156Z","steps":["trace[954083406] 'process raft request' (duration: 44.824299ms)","trace[954083406] 'compare' (duration: 64.436474ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:00:39.861521Z","caller":"traceutil/trace.go:171","msg":"trace[1381937878] transaction","detail":"{read_only:false; response_revision:6762; number_of_response:1; }","duration":"182.680737ms","start":"2026-08-13T01:00:39.678822Z","end":"2026-08-13T01:00:39.861503Z","steps":["trace[1381937878] 'process raft request' (duration: 182.646826ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:00:39.861544Z","caller":"traceutil/trace.go:171","msg":"trace[1813663559] linearizableReadLoop","detail":"{readStateIndex:7014; appliedIndex:7013; }","duration":"193.043115ms","start":"2026-08-13T01:00:39.668469Z","end":"2026-08-13T01:00:39.861512Z","steps":["trace[1813663559] 'read index received' (duration: 142.885823ms)","trace[1813663559] 'applied index is now lower than readState.Index' (duration: 50.154612ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:00:39.861578Z","caller":"traceutil/trace.go:171","msg":"trace[442368138] transaction","detail":"{read_only:false; response_revision:6760; number_of_response:1; }","duration":"238.626644ms","start":"2026-08-13T01:00:39.622881Z","end":"2026-08-13T01:00:39.861508Z","steps":["trace[442368138] 'process raft request' (duration: 188.471092ms)","trace[442368138] 'compare' (duration: 49.987408ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:00:39.861653Z","caller":"traceutil/trace.go:171","msg":"trace[395765143] transaction","detail":"{read_only:false; response_revision:6761; number_of_response:1; }","duration":"189.846559ms","start":"2026-08-13T01:00:39.671761Z","end":"2026-08-13T01:00:39.861607Z","steps":["trace[395765143] 'process raft request' (duration: 189.673424ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:00:39.861726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.507971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-13T01:00:39.861757Z","caller":"traceutil/trace.go:171","msg":"trace[82349545] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6762; }","duration":"238.560973ms","start":"2026-08-13T01:00:39.623188Z","end":"2026-08-13T01:00:39.861749Z","steps":["trace[82349545] 'agreement among raft nodes before linearized reading' (duration: 238.4559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:00:39.861723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.696557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:00:39.861827Z","caller":"traceutil/trace.go:171","msg":"trace[1171203581] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:6762; }","duration":"139.866941ms","start":"2026-08-13T01:00:39.721951Z","end":"2026-08-13T01:00:39.861818Z","steps":["trace[1171203581] 'agreement among raft nodes before linearized reading' (duration: 139.708707ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:00:40.135651Z","caller":"traceutil/trace.go:171","msg":"trace[477396459] transaction","detail":"{read_only:false; response_revision:6763; number_of_response:1; }","duration":"269.764424ms","start":"2026-08-13T01:00:39.865869Z","end":"2026-08-13T01:00:40.135634Z","steps":["trace[477396459] 'process raft request' (duration: 269.587849ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:01:48.704191Z","caller":"traceutil/trace.go:171","msg":"trace[813216559] linearizableReadLoop","detail":"{readStateIndex:7400; appliedIndex:7399; }","duration":"104.940524ms","start":"2026-08-13T01:01:48.599226Z","end":"2026-08-13T01:01:48.704167Z","steps":["trace[813216559] 'read index received' (duration: 104.726928ms)","trace[813216559] 'applied index is now lower than readState.Index' (duration: 212.606µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:01:48.704352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.088769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:01:48.704329Z","caller":"traceutil/trace.go:171","msg":"trace[627448246] transaction","detail":"{read_only:false; response_revision:7132; number_of_response:1; }","duration":"121.975538ms","start":"2026-08-13T01:01:48.582323Z","end":"2026-08-13T01:01:48.704299Z","steps":["trace[627448246] 'process raft request' (duration: 121.70487ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:01:48.704401Z","caller":"traceutil/trace.go:171","msg":"trace[1621754046] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:7132; }","duration":"105.206382ms","start":"2026-08-13T01:01:48.599181Z","end":"2026-08-13T01:01:48.704388Z","steps":["trace[1621754046] 'agreement among raft nodes before linearized reading' (duration: 105.083818ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:02:29.271611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5451} {"level":"info","ts":"2026-08-13T01:02:29.350283Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5451,"took":"77.124311ms","hash":141790459,"current-db-size-bytes":53895168,"current-db-size":"54 MB","current-db-size-in-use-bytes":18481152,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-13T01:02:29.350363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":141790459,"revision":5451,"compact-revision":2820}