{"level":"warn","ts":"2026-09-10T01:49:52.964267Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T01:49:52.964348Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.127: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://162.253.55.127:2380","--initial-cluster=instance=https://162.253.55.127:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.127:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.127: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-09-10T01:49:52.964414Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T01:49:52.964425Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.127:2380"]} {"level":"info","ts":"2026-09-10T01:49:52.964443Z","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-09-10T01:49:52.966218Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.127:2379"]} {"level":"info","ts":"2026-09-10T01:49:52.966332Z","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://162.253.55.127:2380"],"listen-peer-urls":["https://162.253.55.127:2380"],"advertise-client-urls":["https://162.253.55.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.127: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-09-10T01:49:52.971337Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.687981ms"} {"level":"info","ts":"2026-09-10T01:49:52.980528Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9e5e76b85fbfd435","cluster-id":"7d97e4b11cbd31c2"} {"level":"info","ts":"2026-09-10T01:49:52.98063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T01:49:52.980654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became follower at term 0"} {"level":"info","ts":"2026-09-10T01:49:52.980666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9e5e76b85fbfd435 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T01:49:52.980674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became follower at term 1"} {"level":"info","ts":"2026-09-10T01:49:52.980712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 switched to configuration voters=(11411689040055882805)"} {"level":"warn","ts":"2026-09-10T01:49:52.988193Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T01:49:52.989903Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T01:49:52.991283Z","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-09-10T01:49:52.99316Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9e5e76b85fbfd435","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T01:49:52.993403Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9e5e76b85fbfd435","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T01:49:52.993467Z","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-09-10T01:49:52.993514Z","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-09-10T01:49:52.993524Z","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-09-10T01:49:52.994251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 switched to configuration voters=(11411689040055882805)"} {"level":"info","ts":"2026-09-10T01:49:52.99458Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7d97e4b11cbd31c2","local-member-id":"9e5e76b85fbfd435","added-peer-id":"9e5e76b85fbfd435","added-peer-peer-urls":["https://162.253.55.127:2380"]} {"level":"info","ts":"2026-09-10T01:49:52.996508Z","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-09-10T01:49:52.996573Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.127:2380"} {"level":"info","ts":"2026-09-10T01:49:52.996603Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.127:2380"} {"level":"info","ts":"2026-09-10T01:49:52.996715Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9e5e76b85fbfd435","initial-advertise-peer-urls":["https://162.253.55.127:2380"],"listen-peer-urls":["https://162.253.55.127:2380"],"advertise-client-urls":["https://162.253.55.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T01:49:52.996759Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T01:49:53.681552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T01:49:53.681653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T01:49:53.681699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 received MsgPreVoteResp from 9e5e76b85fbfd435 at term 1"} {"level":"info","ts":"2026-09-10T01:49:53.68172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became candidate at term 2"} {"level":"info","ts":"2026-09-10T01:49:53.681734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 received MsgVoteResp from 9e5e76b85fbfd435 at term 2"} {"level":"info","ts":"2026-09-10T01:49:53.681752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became leader at term 2"} {"level":"info","ts":"2026-09-10T01:49:53.681767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9e5e76b85fbfd435 elected leader 9e5e76b85fbfd435 at term 2"} {"level":"info","ts":"2026-09-10T01:49:53.686051Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:49:53.687405Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9e5e76b85fbfd435","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.127:2379]}","request-path":"/0/members/9e5e76b85fbfd435/attributes","cluster-id":"7d97e4b11cbd31c2","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T01:49:53.687577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T01:49:53.68768Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T01:49:53.687875Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T01:49:53.687892Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T01:49:53.687912Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7d97e4b11cbd31c2","local-member-id":"9e5e76b85fbfd435","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:49:53.688009Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:49:53.688033Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:49:53.690579Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.127:2379"} {"level":"info","ts":"2026-09-10T01:49:53.690777Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-10T01:50:03.826654Z","caller":"traceutil/trace.go:171","msg":"trace[1288056731] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"108.853291ms","start":"2026-09-10T01:50:03.717764Z","end":"2026-09-10T01:50:03.826618Z","steps":["trace[1288056731] 'read index received' (duration: 40.058988ms)","trace[1288056731] 'applied index is now lower than readState.Index' (duration: 68.793003ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:50:03.826815Z","caller":"traceutil/trace.go:171","msg":"trace[821946601] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"132.419809ms","start":"2026-09-10T01:50:03.694348Z","end":"2026-09-10T01:50:03.826768Z","steps":["trace[821946601] 'process raft request' (duration: 63.543034ms)","trace[821946601] 'compare' (duration: 68.546088ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:50:03.82693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.127868ms","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-09-10T01:50:03.827028Z","caller":"traceutil/trace.go:171","msg":"trace[681395740] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:370; }","duration":"109.303632ms","start":"2026-09-10T01:50:03.717708Z","end":"2026-09-10T01:50:03.827011Z","steps":["trace[681395740] 'agreement among raft nodes before linearized reading' (duration: 109.125818ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:12.730114Z","caller":"traceutil/trace.go:171","msg":"trace[1065271791] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"177.354699ms","start":"2026-09-10T01:50:12.55273Z","end":"2026-09-10T01:50:12.730085Z","steps":["trace[1065271791] 'process raft request' (duration: 177.155515ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:23.861006Z","caller":"traceutil/trace.go:171","msg":"trace[1737379668] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"111.13203ms","start":"2026-09-10T01:50:23.749848Z","end":"2026-09-10T01:50:23.86098Z","steps":["trace[1737379668] 'process raft request' (duration: 110.977017ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:34.006886Z","caller":"traceutil/trace.go:171","msg":"trace[783126356] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"100.398595ms","start":"2026-09-10T01:50:33.906463Z","end":"2026-09-10T01:50:34.006861Z","steps":["trace[783126356] 'process raft request' (duration: 71.161522ms)","trace[783126356] 'compare' (duration: 28.666173ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:50:34.137302Z","caller":"traceutil/trace.go:171","msg":"trace[1029928275] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"131.083553ms","start":"2026-09-10T01:50:34.006181Z","end":"2026-09-10T01:50:34.137264Z","steps":["trace[1029928275] 'process raft request' (duration: 112.07032ms)","trace[1029928275] 'compare' (duration: 18.671227ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:50:34.137411Z","caller":"traceutil/trace.go:171","msg":"trace[667433846] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"109.325732ms","start":"2026-09-10T01:50:34.028037Z","end":"2026-09-10T01:50:34.137362Z","steps":["trace[667433846] 'process raft request' (duration: 109.176779ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:34.137722Z","caller":"traceutil/trace.go:171","msg":"trace[1223795415] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"105.17577ms","start":"2026-09-10T01:50:34.032517Z","end":"2026-09-10T01:50:34.137693Z","steps":["trace[1223795415] 'process raft request' (duration: 104.765833ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:34.137661Z","caller":"traceutil/trace.go:171","msg":"trace[1015377713] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"119.749635ms","start":"2026-09-10T01:50:34.017678Z","end":"2026-09-10T01:50:34.137427Z","steps":["trace[1015377713] 'process raft request' (duration: 119.46055ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:34.137882Z","caller":"traceutil/trace.go:171","msg":"trace[418897357] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:581; }","duration":"131.207464ms","start":"2026-09-10T01:50:34.006648Z","end":"2026-09-10T01:50:34.137855Z","steps":["trace[418897357] 'read index received' (duration: 52.421716ms)","trace[418897357] 'applied index is now lower than readState.Index' (duration: 78.784218ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:50:34.137924Z","caller":"traceutil/trace.go:171","msg":"trace[579931202] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"105.349203ms","start":"2026-09-10T01:50:34.032561Z","end":"2026-09-10T01:50:34.13791Z","steps":["trace[579931202] 'process raft request' (duration: 105.121079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:34.138032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.963692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-09-10T01:50:34.138113Z","caller":"traceutil/trace.go:171","msg":"trace[623129735] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:565; }","duration":"176.043133ms","start":"2026-09-10T01:50:33.962016Z","end":"2026-09-10T01:50:34.138059Z","steps":["trace[623129735] 'agreement among raft nodes before linearized reading' (duration: 175.911841ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:34.445788Z","caller":"traceutil/trace.go:171","msg":"trace[608161925] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"107.185614ms","start":"2026-09-10T01:50:34.338567Z","end":"2026-09-10T01:50:34.445753Z","steps":["trace[608161925] 'process raft request' (duration: 91.857216ms)","trace[608161925] 'compare' (duration: 14.642757ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:50:38.207959Z","caller":"traceutil/trace.go:171","msg":"trace[2069865908] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"324.499032ms","start":"2026-09-10T01:50:37.883434Z","end":"2026-09-10T01:50:38.207933Z","steps":["trace[2069865908] 'process raft request' (duration: 324.37642ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:38.208505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:37.883419Z","time spent":"324.609353ms","remote":"127.0.0.1:43866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T01:50:38.218869Z","caller":"traceutil/trace.go:171","msg":"trace[298952129] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"135.35879ms","start":"2026-09-10T01:50:38.083456Z","end":"2026-09-10T01:50:38.218815Z","steps":["trace[298952129] 'process raft request' (duration: 135.156077ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:44.353037Z","caller":"traceutil/trace.go:171","msg":"trace[119115123] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"100.411065ms","start":"2026-09-10T01:50:44.252603Z","end":"2026-09-10T01:50:44.353014Z","steps":["trace[119115123] 'process raft request' (duration: 100.252932ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:45.141266Z","caller":"traceutil/trace.go:171","msg":"trace[1974648174] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:733; }","duration":"222.466404ms","start":"2026-09-10T01:50:44.91872Z","end":"2026-09-10T01:50:45.141187Z","steps":["trace[1974648174] 'read index received' (duration: 222.237371ms)","trace[1974648174] 'applied index is now lower than readState.Index' (duration: 228.063µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:50:45.141309Z","caller":"traceutil/trace.go:171","msg":"trace[1879488017] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"427.180491ms","start":"2026-09-10T01:50:44.714082Z","end":"2026-09-10T01:50:45.141263Z","steps":["trace[1879488017] 'process raft request' (duration: 426.937717ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:45.141553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.79553ms","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-09-10T01:50:45.141591Z","caller":"traceutil/trace.go:171","msg":"trace[1125269068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:710; }","duration":"222.916302ms","start":"2026-09-10T01:50:44.918665Z","end":"2026-09-10T01:50:45.141581Z","steps":["trace[1125269068] 'agreement among raft nodes before linearized reading' (duration: 222.738309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:45.141724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:44.714061Z","time spent":"427.358233ms","remote":"127.0.0.1:43986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T01:50:45.142056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.22422ms","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-09-10T01:50:45.142172Z","caller":"traceutil/trace.go:171","msg":"trace[243174991] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:710; }","duration":"216.372832ms","start":"2026-09-10T01:50:44.925772Z","end":"2026-09-10T01:50:45.142145Z","steps":["trace[243174991] 'agreement among raft nodes before linearized reading' (duration: 216.093737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:45.78148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.803044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:50:45.781607Z","caller":"traceutil/trace.go:171","msg":"trace[1251233042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:712; }","duration":"349.979897ms","start":"2026-09-10T01:50:45.431592Z","end":"2026-09-10T01:50:45.781572Z","steps":["trace[1251233042] 'range keys from in-memory index tree' (duration: 349.678461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:45.781671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:45.43157Z","time spent":"350.084208ms","remote":"127.0.0.1:43792","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-09-10T01:51:03.513998Z","caller":"traceutil/trace.go:171","msg":"trace[837204121] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:862; }","duration":"217.893222ms","start":"2026-09-10T01:51:03.296087Z","end":"2026-09-10T01:51:03.513981Z","steps":["trace[837204121] 'read index received' (duration: 217.721657ms)","trace[837204121] 'applied index is now lower than readState.Index' (duration: 170.745µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:51:03.514364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.622357ms","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-09-10T01:51:03.514491Z","caller":"traceutil/trace.go:171","msg":"trace[540574013] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:833; }","duration":"176.764451ms","start":"2026-09-10T01:51:03.337698Z","end":"2026-09-10T01:51:03.514463Z","steps":["trace[540574013] 'agreement among raft nodes before linearized reading' (duration: 176.571286ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:51:03.514865Z","caller":"traceutil/trace.go:171","msg":"trace[571418534] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"246.36037ms","start":"2026-09-10T01:51:03.268463Z","end":"2026-09-10T01:51:03.514823Z","steps":["trace[571418534] 'process raft request' (duration: 245.409371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:51:03.515077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.35864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:51:03.515176Z","caller":"traceutil/trace.go:171","msg":"trace[904378946] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:833; }","duration":"177.464773ms","start":"2026-09-10T01:51:03.337698Z","end":"2026-09-10T01:51:03.515163Z","steps":["trace[904378946] 'agreement among raft nodes before linearized reading' (duration: 177.331179ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:51:03.515137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.995185ms","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-09-10T01:51:03.515307Z","caller":"traceutil/trace.go:171","msg":"trace[1214729050] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:833; }","duration":"219.249513ms","start":"2026-09-10T01:51:03.296042Z","end":"2026-09-10T01:51:03.515292Z","steps":["trace[1214729050] 'agreement among raft nodes before linearized reading' (duration: 218.887672ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:22.29149Z","caller":"traceutil/trace.go:171","msg":"trace[1338318643] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"304.86567ms","start":"2026-09-10T01:52:21.986603Z","end":"2026-09-10T01:52:22.291469Z","steps":["trace[1338318643] 'process raft request' (duration: 304.683547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:22.291616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:21.986585Z","time spent":"304.971422ms","remote":"127.0.0.1:44042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:52:22.29147Z","caller":"traceutil/trace.go:171","msg":"trace[104425500] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1973; }","duration":"276.360352ms","start":"2026-09-10T01:52:22.015084Z","end":"2026-09-10T01:52:22.291445Z","steps":["trace[104425500] 'read index received' (duration: 276.213159ms)","trace[104425500] 'applied index is now lower than readState.Index' (duration: 146.303µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:22.291665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.561607ms","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-09-10T01:52:22.291713Z","caller":"traceutil/trace.go:171","msg":"trace[1729084549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1901; }","duration":"276.655268ms","start":"2026-09-10T01:52:22.015048Z","end":"2026-09-10T01:52:22.291703Z","steps":["trace[1729084549] 'agreement among raft nodes before linearized reading' (duration: 276.501225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:22.487016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.948754ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-10T01:52:22.487346Z","caller":"traceutil/trace.go:171","msg":"trace[1246747516] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1974; }","duration":"195.665914ms","start":"2026-09-10T01:52:22.291662Z","end":"2026-09-10T01:52:22.487328Z","steps":["trace[1246747516] 'read index received' (duration: 79.288571ms)","trace[1246747516] 'applied index is now lower than readState.Index' (duration: 116.376093ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:52:22.487381Z","caller":"traceutil/trace.go:171","msg":"trace[1233391442] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"273.863941ms","start":"2026-09-10T01:52:22.213479Z","end":"2026-09-10T01:52:22.487343Z","steps":["trace[1233391442] 'process raft request' (duration: 273.627906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:22.48772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.477166ms","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-09-10T01:52:22.487765Z","caller":"traceutil/trace.go:171","msg":"trace[2005963072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1903; }","duration":"172.557067ms","start":"2026-09-10T01:52:22.315195Z","end":"2026-09-10T01:52:22.487752Z","steps":["trace[2005963072] 'agreement among raft nodes before linearized reading' (duration: 172.396314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:22.487741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.803721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-10T01:52:22.487773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.315057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-10T01:52:22.487822Z","caller":"traceutil/trace.go:171","msg":"trace[2020691497] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1903; }","duration":"322.44931ms","start":"2026-09-10T01:52:22.165359Z","end":"2026-09-10T01:52:22.487808Z","steps":["trace[2020691497] 'agreement among raft nodes before linearized reading' (duration: 322.077143ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:22.487828Z","caller":"traceutil/trace.go:171","msg":"trace[839099829] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1903; }","duration":"150.959064ms","start":"2026-09-10T01:52:22.336845Z","end":"2026-09-10T01:52:22.487804Z","steps":["trace[839099829] 'agreement among raft nodes before linearized reading' (duration: 150.783201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:22.487861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:22.165336Z","time spent":"322.516761ms","remote":"127.0.0.1:44042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-10T01:52:22.487871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.555722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e7af7fd2-03c2-4fe7-9d5e-8e791a7f3bc0\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-09-10T01:52:22.488044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.733593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e7af7fd2-03c2-4fe7-9d5e-8e791a7f3bc0\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-10T01:52:22.488052Z","caller":"traceutil/trace.go:171","msg":"trace[1030173445] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e7af7fd2-03c2-4fe7-9d5e-8e791a7f3bc0; range_end:; response_count:1; response_revision:1903; }","duration":"287.798477ms","start":"2026-09-10T01:52:22.200231Z","end":"2026-09-10T01:52:22.488029Z","steps":["trace[1030173445] 'agreement among raft nodes before linearized reading' (duration: 287.220435ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:22.488089Z","caller":"traceutil/trace.go:171","msg":"trace[1868901049] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e7af7fd2-03c2-4fe7-9d5e-8e791a7f3bc0; range_end:; response_count:1; response_revision:1903; }","duration":"299.794055ms","start":"2026-09-10T01:52:22.188272Z","end":"2026-09-10T01:52:22.488066Z","steps":["trace[1868901049] 'agreement among raft nodes before linearized reading' (duration: 299.206243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:22.488132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.042066ms","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-09-10T01:52:22.488188Z","caller":"traceutil/trace.go:171","msg":"trace[92405334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1903; }","duration":"168.119997ms","start":"2026-09-10T01:52:22.320052Z","end":"2026-09-10T01:52:22.488172Z","steps":["trace[92405334] 'agreement among raft nodes before linearized reading' (duration: 167.886252ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:22.488324Z","caller":"traceutil/trace.go:171","msg":"trace[1575318271] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"192.566259ms","start":"2026-09-10T01:52:22.295736Z","end":"2026-09-10T01:52:22.488302Z","steps":["trace[1575318271] 'process raft request' (duration: 191.517747ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:22.840921Z","caller":"traceutil/trace.go:171","msg":"trace[1109211947] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1978; }","duration":"127.008251ms","start":"2026-09-10T01:52:22.713887Z","end":"2026-09-10T01:52:22.840896Z","steps":["trace[1109211947] 'read index received' (duration: 56.968761ms)","trace[1109211947] 'applied index is now lower than readState.Index' (duration: 70.03826ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:52:22.841062Z","caller":"traceutil/trace.go:171","msg":"trace[412609586] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"347.996066ms","start":"2026-09-10T01:52:22.492942Z","end":"2026-09-10T01:52:22.840938Z","steps":["trace[412609586] 'process raft request' (duration: 277.793402ms)","trace[412609586] 'compare' (duration: 69.883158ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:52:22.841271Z","caller":"traceutil/trace.go:171","msg":"trace[1959680649] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"344.427062ms","start":"2026-09-10T01:52:22.496819Z","end":"2026-09-10T01:52:22.841246Z","steps":["trace[1959680649] 'process raft request' (duration: 344.016794ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:22.841316Z","caller":"traceutil/trace.go:171","msg":"trace[1861871813] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"348.232351ms","start":"2026-09-10T01:52:22.493055Z","end":"2026-09-10T01:52:22.841288Z","steps":["trace[1861871813] 'process raft request' (duration: 347.71263ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:22.841565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:22.496802Z","time spent":"344.523735ms","remote":"127.0.0.1:43986","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-09-10T01:52:22.841517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.476561ms","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-09-10T01:52:22.841625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:22.493032Z","time spent":"348.469686ms","remote":"127.0.0.1:44042","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-09-10T01:52:22.841661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:22.492896Z","time spent":"348.288232ms","remote":"127.0.0.1:44042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:52:22.84166Z","caller":"traceutil/trace.go:171","msg":"trace[802254309] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1907; }","duration":"127.796728ms","start":"2026-09-10T01:52:22.71384Z","end":"2026-09-10T01:52:22.841636Z","steps":["trace[802254309] 'agreement among raft nodes before linearized reading' (duration: 127.184625ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:25.673028Z","caller":"traceutil/trace.go:171","msg":"trace[629321177] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"109.547283ms","start":"2026-09-10T01:52:25.563456Z","end":"2026-09-10T01:52:25.673003Z","steps":["trace[629321177] 'process raft request' (duration: 98.202772ms)","trace[629321177] 'compare' (duration: 10.928272ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:52:35.966853Z","caller":"traceutil/trace.go:171","msg":"trace[276272997] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"201.887486ms","start":"2026-09-10T01:52:35.764934Z","end":"2026-09-10T01:52:35.966821Z","steps":["trace[276272997] 'process raft request' (duration: 201.729283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:52.369874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.705005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:52:52.370108Z","caller":"traceutil/trace.go:171","msg":"trace[645022632] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"290.661061ms","start":"2026-09-10T01:52:52.079421Z","end":"2026-09-10T01:52:52.370083Z","steps":["trace[645022632] 'process raft request' (duration: 114.66517ms)","trace[645022632] 'compare' (duration: 175.600423ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:52:52.370478Z","caller":"traceutil/trace.go:171","msg":"trace[308400896] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2200; }","duration":"270.852944ms","start":"2026-09-10T01:52:52.099613Z","end":"2026-09-10T01:52:52.370466Z","steps":["trace[308400896] 'read index received' (duration: 94.421544ms)","trace[308400896] 'applied index is now lower than readState.Index' (duration: 176.42978ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:52.529644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.984272ms","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-09-10T01:52:52.530062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.914335ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-10T01:52:52.529781Z","caller":"traceutil/trace.go:171","msg":"trace[971146281] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2122; }","duration":"430.172305ms","start":"2026-09-10T01:52:52.099582Z","end":"2026-09-10T01:52:52.529754Z","steps":["trace[971146281] 'agreement among raft nodes before linearized reading' (duration: 271.024966ms)","trace[971146281] 'range keys from in-memory index tree' (duration: 158.863043ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:52:52.530206Z","caller":"traceutil/trace.go:171","msg":"trace[753495505] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"159.604178ms","start":"2026-09-10T01:52:52.370576Z","end":"2026-09-10T01:52:52.53018Z","steps":["trace[753495505] 'read index received' (duration: 58.761µs)","trace[753495505] 'applied index is now lower than readState.Index' (duration: 159.543017ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:52.53038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.25554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:52:52.530477Z","caller":"traceutil/trace.go:171","msg":"trace[523217064] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2122; }","duration":"228.352932ms","start":"2026-09-10T01:52:52.302087Z","end":"2026-09-10T01:52:52.53044Z","steps":["trace[523217064] 'agreement among raft nodes before linearized reading' (duration: 228.21893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:52.530524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.992155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-10T01:52:52.530261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:52.09957Z","time spent":"430.657975ms","remote":"127.0.0.1:59394","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-09-10T01:52:52.53063Z","caller":"traceutil/trace.go:171","msg":"trace[311219277] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2122; }","duration":"128.139327ms","start":"2026-09-10T01:52:52.402467Z","end":"2026-09-10T01:52:52.530606Z","steps":["trace[311219277] 'agreement among raft nodes before linearized reading' (duration: 127.983034ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:52.732638Z","caller":"traceutil/trace.go:171","msg":"trace[716910378] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"154.44333ms","start":"2026-09-10T01:52:52.578173Z","end":"2026-09-10T01:52:52.732617Z","steps":["trace[716910378] 'read index received' (duration: 154.210515ms)","trace[716910378] 'applied index is now lower than readState.Index' (duration: 231.665µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:52:52.732768Z","caller":"traceutil/trace.go:171","msg":"trace[1122862839] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"197.767257ms","start":"2026-09-10T01:52:52.534992Z","end":"2026-09-10T01:52:52.732759Z","steps":["trace[1122862839] 'process raft request' (duration: 197.435521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:52.732885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.693304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:52:52.732947Z","caller":"traceutil/trace.go:171","msg":"trace[835531024] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2123; }","duration":"154.766775ms","start":"2026-09-10T01:52:52.578163Z","end":"2026-09-10T01:52:52.73293Z","steps":["trace[835531024] 'agreement among raft nodes before linearized reading' (duration: 154.669744ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:52.810182Z","caller":"traceutil/trace.go:171","msg":"trace[1343427558] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"113.360935ms","start":"2026-09-10T01:52:52.696781Z","end":"2026-09-10T01:52:52.810142Z","steps":["trace[1343427558] 'process raft request' (duration: 112.998018ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:53:46.681074Z","caller":"traceutil/trace.go:171","msg":"trace[397319557] transaction","detail":"{read_only:false; response_revision:2426; number_of_response:1; }","duration":"113.4221ms","start":"2026-09-10T01:53:46.567632Z","end":"2026-09-10T01:53:46.681054Z","steps":["trace[397319557] 'process raft request' (duration: 113.304938ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:54:09.652618Z","caller":"traceutil/trace.go:171","msg":"trace[690105982] transaction","detail":"{read_only:false; response_revision:2643; number_of_response:1; }","duration":"237.091635ms","start":"2026-09-10T01:54:09.41549Z","end":"2026-09-10T01:54:09.652581Z","steps":["trace[690105982] 'process raft request' (duration: 237.034484ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:54:09.652796Z","caller":"traceutil/trace.go:171","msg":"trace[481484787] transaction","detail":"{read_only:false; response_revision:2642; number_of_response:1; }","duration":"238.007251ms","start":"2026-09-10T01:54:09.414766Z","end":"2026-09-10T01:54:09.652773Z","steps":["trace[481484787] 'process raft request' (duration: 232.845621ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:54:39.336363Z","caller":"traceutil/trace.go:171","msg":"trace[1735861235] linearizableReadLoop","detail":"{readStateIndex:2907; appliedIndex:2906; }","duration":"126.433135ms","start":"2026-09-10T01:54:39.209783Z","end":"2026-09-10T01:54:39.336216Z","steps":["trace[1735861235] 'read index received' (duration: 126.182971ms)","trace[1735861235] 'applied index is now lower than readState.Index' (duration: 248.424µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:54:39.336716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.915283ms","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-09-10T01:54:39.336773Z","caller":"traceutil/trace.go:171","msg":"trace[432934675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2803; }","duration":"127.007485ms","start":"2026-09-10T01:54:39.209753Z","end":"2026-09-10T01:54:39.336761Z","steps":["trace[432934675] 'agreement among raft nodes before linearized reading' (duration: 126.814862ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:54:39.336498Z","caller":"traceutil/trace.go:171","msg":"trace[868456507] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"133.899784ms","start":"2026-09-10T01:54:39.202569Z","end":"2026-09-10T01:54:39.336469Z","steps":["trace[868456507] 'process raft request' (duration: 133.424416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:01.367571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.127104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:59:01.36791Z","caller":"traceutil/trace.go:171","msg":"trace[912194275] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"118.554383ms","start":"2026-09-10T01:59:01.249311Z","end":"2026-09-10T01:59:01.367866Z","steps":["trace[912194275] 'process raft request' (duration: 16.065911ms)","trace[912194275] 'compare' (duration: 101.94708ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:59:01.367993Z","caller":"traceutil/trace.go:171","msg":"trace[727791928] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"103.871955ms","start":"2026-09-10T01:59:01.264059Z","end":"2026-09-10T01:59:01.367931Z","steps":["trace[727791928] 'process raft request' (duration: 103.643309ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:01.368073Z","caller":"traceutil/trace.go:171","msg":"trace[482928248] linearizableReadLoop","detail":"{readStateIndex:4868; appliedIndex:4867; }","duration":"118.257556ms","start":"2026-09-10T01:59:01.249787Z","end":"2026-09-10T01:59:01.368045Z","steps":["trace[482928248] 'read index received' (duration: 15.56596ms)","trace[482928248] 'applied index is now lower than readState.Index' (duration: 102.687996ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:59:01.368324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.519442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-patch-p8pd7\" ","response":"range_response_count:1 size:3378"} {"level":"info","ts":"2026-09-10T01:59:01.368379Z","caller":"traceutil/trace.go:171","msg":"trace[1917935240] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-patch-p8pd7; range_end:; response_count:1; response_revision:4687; }","duration":"118.627585ms","start":"2026-09-10T01:59:01.249735Z","end":"2026-09-10T01:59:01.368363Z","steps":["trace[1917935240] 'agreement among raft nodes before linearized reading' (duration: 118.392729ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:06.264439Z","caller":"traceutil/trace.go:171","msg":"trace[336031161] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"107.570145ms","start":"2026-09-10T01:59:06.156841Z","end":"2026-09-10T01:59:06.264411Z","steps":["trace[336031161] 'process raft request' (duration: 106.90825ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:07.702415Z","caller":"traceutil/trace.go:171","msg":"trace[736827316] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"122.956705ms","start":"2026-09-10T01:59:07.579425Z","end":"2026-09-10T01:59:07.702382Z","steps":["trace[736827316] 'process raft request' (duration: 118.027202ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:08.990602Z","caller":"traceutil/trace.go:171","msg":"trace[534378603] linearizableReadLoop","detail":"{readStateIndex:5071; appliedIndex:5070; }","duration":"123.086814ms","start":"2026-09-10T01:59:08.867491Z","end":"2026-09-10T01:59:08.990577Z","steps":["trace[534378603] 'read index received' (duration: 122.91022ms)","trace[534378603] 'applied index is now lower than readState.Index' (duration: 175.494µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:59:08.990686Z","caller":"traceutil/trace.go:171","msg":"trace[157262692] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"128.994659ms","start":"2026-09-10T01:59:08.861659Z","end":"2026-09-10T01:59:08.990654Z","steps":["trace[157262692] 'process raft request' (duration: 128.742713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:08.990823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.309559ms","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-09-10T01:59:08.990865Z","caller":"traceutil/trace.go:171","msg":"trace[354732562] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4886; }","duration":"123.390972ms","start":"2026-09-10T01:59:08.86746Z","end":"2026-09-10T01:59:08.990851Z","steps":["trace[354732562] 'agreement among raft nodes before linearized reading' (duration: 123.210277ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:16.505837Z","caller":"traceutil/trace.go:171","msg":"trace[111765218] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"104.381915ms","start":"2026-09-10T01:59:16.401419Z","end":"2026-09-10T01:59:16.505801Z","steps":["trace[111765218] 'process raft request' (duration: 80.103584ms)","trace[111765218] 'compare' (duration: 24.175108ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:59:16.753819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.648388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-10T01:59:16.753923Z","caller":"traceutil/trace.go:171","msg":"trace[197724674] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4971; }","duration":"125.76639ms","start":"2026-09-10T01:59:16.628112Z","end":"2026-09-10T01:59:16.753878Z","steps":["trace[197724674] 'range keys from in-memory index tree' (duration: 125.459204ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:16.957268Z","caller":"traceutil/trace.go:171","msg":"trace[1992483896] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"191.907588ms","start":"2026-09-10T01:59:16.765329Z","end":"2026-09-10T01:59:16.957236Z","steps":["trace[1992483896] 'process raft request' (duration: 191.650872ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:16.957368Z","caller":"traceutil/trace.go:171","msg":"trace[489250951] linearizableReadLoop","detail":"{readStateIndex:5162; appliedIndex:5161; }","duration":"165.857368ms","start":"2026-09-10T01:59:16.79129Z","end":"2026-09-10T01:59:16.957147Z","steps":["trace[489250951] 'read index received' (duration: 165.619623ms)","trace[489250951] 'applied index is now lower than readState.Index' (duration: 237.025µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:59:16.957439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.133064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-10T01:59:16.95747Z","caller":"traceutil/trace.go:171","msg":"trace[1373332415] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4972; }","duration":"166.212696ms","start":"2026-09-10T01:59:16.791248Z","end":"2026-09-10T01:59:16.957461Z","steps":["trace[1373332415] 'agreement among raft nodes before linearized reading' (duration: 166.061203ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:17.18584Z","caller":"traceutil/trace.go:171","msg":"trace[15404675] linearizableReadLoop","detail":"{readStateIndex:5163; appliedIndex:5162; }","duration":"222.794614ms","start":"2026-09-10T01:59:16.963016Z","end":"2026-09-10T01:59:17.185811Z","steps":["trace[15404675] 'read index received' (duration: 149.354082ms)","trace[15404675] 'applied index is now lower than readState.Index' (duration: 73.438832ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:59:17.186132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.091491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-10T01:59:17.186236Z","caller":"traceutil/trace.go:171","msg":"trace[556487622] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4972; }","duration":"223.212603ms","start":"2026-09-10T01:59:16.962996Z","end":"2026-09-10T01:59:17.186208Z","steps":["trace[556487622] 'agreement among raft nodes before linearized reading' (duration: 222.933957ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:17.205701Z","caller":"traceutil/trace.go:171","msg":"trace[761410903] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"171.053392ms","start":"2026-09-10T01:59:17.03463Z","end":"2026-09-10T01:59:17.205683Z","steps":["trace[761410903] 'process raft request' (duration: 170.739465ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:17.205949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.007934ms","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-09-10T01:59:17.205986Z","caller":"traceutil/trace.go:171","msg":"trace[221002353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4973; }","duration":"157.104347ms","start":"2026-09-10T01:59:17.048873Z","end":"2026-09-10T01:59:17.205977Z","steps":["trace[221002353] 'agreement among raft nodes before linearized reading' (duration: 156.967784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:17.206238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.903466ms","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-09-10T01:59:17.206263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.659187ms","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-09-10T01:59:17.206294Z","caller":"traceutil/trace.go:171","msg":"trace[1756489745] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4973; }","duration":"177.971038ms","start":"2026-09-10T01:59:17.028306Z","end":"2026-09-10T01:59:17.206277Z","steps":["trace[1756489745] 'agreement among raft nodes before linearized reading' (duration: 177.839725ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:17.2063Z","caller":"traceutil/trace.go:171","msg":"trace[1513564122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4973; }","duration":"183.731709ms","start":"2026-09-10T01:59:17.022557Z","end":"2026-09-10T01:59:17.206289Z","steps":["trace[1513564122] 'agreement among raft nodes before linearized reading' (duration: 183.628236ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:17.328073Z","caller":"traceutil/trace.go:171","msg":"trace[13120145] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"117.48094ms","start":"2026-09-10T01:59:17.210545Z","end":"2026-09-10T01:59:17.328026Z","steps":["trace[13120145] 'process raft request' (duration: 81.409453ms)","trace[13120145] 'compare' (duration: 35.639816ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:59:17.328173Z","caller":"traceutil/trace.go:171","msg":"trace[658322676] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"117.457489ms","start":"2026-09-10T01:59:17.210693Z","end":"2026-09-10T01:59:17.32815Z","steps":["trace[658322676] 'process raft request' (duration: 117.0494ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:17.32847Z","caller":"traceutil/trace.go:171","msg":"trace[508738998] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"117.494409ms","start":"2026-09-10T01:59:17.21096Z","end":"2026-09-10T01:59:17.328454Z","steps":["trace[508738998] 'process raft request' (duration: 117.146382ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:17.328752Z","caller":"traceutil/trace.go:171","msg":"trace[422447619] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"116.198331ms","start":"2026-09-10T01:59:17.212538Z","end":"2026-09-10T01:59:17.328737Z","steps":["trace[422447619] 'process raft request' (duration: 115.842933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:17.32901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.140844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-vnl8p\" ","response":"range_response_count:1 size:9757"} {"level":"info","ts":"2026-09-10T01:59:17.329062Z","caller":"traceutil/trace.go:171","msg":"trace[2060379742] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-vnl8p; range_end:; response_count:1; response_revision:4979; }","duration":"118.225876ms","start":"2026-09-10T01:59:17.210822Z","end":"2026-09-10T01:59:17.329048Z","steps":["trace[2060379742] 'agreement among raft nodes before linearized reading' (duration: 117.98746ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:17.485824Z","caller":"traceutil/trace.go:171","msg":"trace[1349766048] linearizableReadLoop","detail":"{readStateIndex:5172; appliedIndex:5171; }","duration":"107.358909ms","start":"2026-09-10T01:59:17.378439Z","end":"2026-09-10T01:59:17.485798Z","steps":["trace[1349766048] 'read index received' (duration: 64.643843ms)","trace[1349766048] 'applied index is now lower than readState.Index' (duration: 42.714096ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:59:17.485903Z","caller":"traceutil/trace.go:171","msg":"trace[112937017] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"144.171754ms","start":"2026-09-10T01:59:17.341686Z","end":"2026-09-10T01:59:17.485858Z","steps":["trace[112937017] 'process raft request' (duration: 101.433127ms)","trace[112937017] 'compare' (duration: 42.382619ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:59:17.486087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.594762ms","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-09-10T01:59:17.48612Z","caller":"traceutil/trace.go:171","msg":"trace[688001209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4981; }","duration":"125.666754ms","start":"2026-09-10T01:59:17.360444Z","end":"2026-09-10T01:59:17.48611Z","steps":["trace[688001209] 'agreement among raft nodes before linearized reading' (duration: 125.509131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:17.486342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.925162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1579"} {"level":"info","ts":"2026-09-10T01:59:17.48637Z","caller":"traceutil/trace.go:171","msg":"trace[919672847] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4981; }","duration":"104.004544ms","start":"2026-09-10T01:59:17.382357Z","end":"2026-09-10T01:59:17.486362Z","steps":["trace[919672847] 'agreement among raft nodes before linearized reading' (duration: 103.892132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:17.486733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.103716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-09-10T01:59:17.486777Z","caller":"traceutil/trace.go:171","msg":"trace[1563787242] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4981; }","duration":"104.334701ms","start":"2026-09-10T01:59:17.382429Z","end":"2026-09-10T01:59:17.486764Z","steps":["trace[1563787242] 'agreement among raft nodes before linearized reading' (duration: 104.055625ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:17.487016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.80458ms","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-09-10T01:59:17.487085Z","caller":"traceutil/trace.go:171","msg":"trace[908008062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4981; }","duration":"130.896932ms","start":"2026-09-10T01:59:17.35617Z","end":"2026-09-10T01:59:17.487067Z","steps":["trace[908008062] 'agreement among raft nodes before linearized reading' (duration: 130.697337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:17.8958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.602185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:59:17.896035Z","caller":"traceutil/trace.go:171","msg":"trace[1346701039] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"331.019723ms","start":"2026-09-10T01:59:17.564999Z","end":"2026-09-10T01:59:17.896019Z","steps":["trace[1346701039] 'process raft request' (duration: 330.979892ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:17.896126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:59:17.564975Z","time spent":"331.098614ms","remote":"127.0.0.1:43902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":937,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T01:59:17.896313Z","caller":"traceutil/trace.go:171","msg":"trace[2135283405] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"399.938512ms","start":"2026-09-10T01:59:17.496342Z","end":"2026-09-10T01:59:17.89628Z","steps":["trace[2135283405] 'process raft request' (duration: 399.560503ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:17.896383Z","caller":"traceutil/trace.go:171","msg":"trace[1396068337] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5173; }","duration":"374.114618ms","start":"2026-09-10T01:59:17.522239Z","end":"2026-09-10T01:59:17.896354Z","steps":["trace[1396068337] 'read index received' (duration: 193.796707ms)","trace[1396068337] 'applied index is now lower than readState.Index' (duration: 180.315001ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:59:17.89648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:59:17.496317Z","time spent":"400.068915ms","remote":"127.0.0.1:54834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1877,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:59:17.896317Z","caller":"traceutil/trace.go:171","msg":"trace[30382570] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"401.635691ms","start":"2026-09-10T01:59:17.494651Z","end":"2026-09-10T01:59:17.896287Z","steps":["trace[30382570] 'process raft request' (duration: 221.446762ms)","trace[30382570] 'compare' (duration: 179.36858ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:59:17.896558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.318483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"warn","ts":"2026-09-10T01:59:17.896596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.135863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:59:17.89661Z","caller":"traceutil/trace.go:171","msg":"trace[976722749] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4985; }","duration":"374.399306ms","start":"2026-09-10T01:59:17.522195Z","end":"2026-09-10T01:59:17.896594Z","steps":["trace[976722749] 'agreement among raft nodes before linearized reading' (duration: 374.254412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:17.896625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:59:17.494635Z","time spent":"401.920669ms","remote":"127.0.0.1:44042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:59:17.896637Z","caller":"traceutil/trace.go:171","msg":"trace[189566142] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4985; }","duration":"148.233865ms","start":"2026-09-10T01:59:17.748388Z","end":"2026-09-10T01:59:17.896622Z","steps":["trace[189566142] 'agreement among raft nodes before linearized reading' (duration: 148.130453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:17.896642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:59:17.522179Z","time spent":"374.455746ms","remote":"127.0.0.1:44000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":1016,"request content":"key:\"/registry/services/specs/monitoring/prometheus-operated\" "} {"level":"info","ts":"2026-09-10T01:59:18.123768Z","caller":"traceutil/trace.go:171","msg":"trace[739100681] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"115.882771ms","start":"2026-09-10T01:59:18.007856Z","end":"2026-09-10T01:59:18.123739Z","steps":["trace[739100681] 'process raft request' (duration: 40.126348ms)","trace[739100681] 'compare' (duration: 75.62212ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:59:18.161289Z","caller":"traceutil/trace.go:171","msg":"trace[1523878482] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"151.829702ms","start":"2026-09-10T01:59:18.009436Z","end":"2026-09-10T01:59:18.161266Z","steps":["trace[1523878482] 'process raft request' (duration: 151.688799ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:18.379005Z","caller":"traceutil/trace.go:171","msg":"trace[233049799] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"186.934847ms","start":"2026-09-10T01:59:18.191847Z","end":"2026-09-10T01:59:18.378782Z","steps":["trace[233049799] 'process raft request' (duration: 100.958613ms)","trace[233049799] 'compare' (duration: 85.722148ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:59:18.383274Z","caller":"traceutil/trace.go:171","msg":"trace[1541185543] linearizableReadLoop","detail":"{readStateIndex:5184; appliedIndex:5180; }","duration":"166.139807ms","start":"2026-09-10T01:59:18.217117Z","end":"2026-09-10T01:59:18.383257Z","steps":["trace[1541185543] 'read index received' (duration: 75.752843ms)","trace[1541185543] 'applied index is now lower than readState.Index' (duration: 90.386034ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:59:18.383432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.30577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-vnl8p.18d3d31362b87a7c\" ","response":"range_response_count:1 size:921"} {"level":"info","ts":"2026-09-10T01:59:18.383563Z","caller":"traceutil/trace.go:171","msg":"trace[295904539] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"188.326218ms","start":"2026-09-10T01:59:18.195211Z","end":"2026-09-10T01:59:18.383537Z","steps":["trace[295904539] 'process raft request' (duration: 187.95832ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:18.383591Z","caller":"traceutil/trace.go:171","msg":"trace[674255110] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"191.681194ms","start":"2026-09-10T01:59:18.191846Z","end":"2026-09-10T01:59:18.383528Z","steps":["trace[674255110] 'process raft request' (duration: 191.200233ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:18.383566Z","caller":"traceutil/trace.go:171","msg":"trace[2082287959] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"191.445508ms","start":"2026-09-10T01:59:18.192097Z","end":"2026-09-10T01:59:18.383543Z","steps":["trace[2082287959] 'process raft request' (duration: 191.015149ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:18.38347Z","caller":"traceutil/trace.go:171","msg":"trace[880896503] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-vnl8p.18d3d31362b87a7c; range_end:; response_count:1; response_revision:4993; }","duration":"166.401272ms","start":"2026-09-10T01:59:18.217057Z","end":"2026-09-10T01:59:18.383459Z","steps":["trace[880896503] 'agreement among raft nodes before linearized reading' (duration: 166.258959ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:21.52709Z","caller":"traceutil/trace.go:171","msg":"trace[1573770163] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"183.073587ms","start":"2026-09-10T01:59:21.343987Z","end":"2026-09-10T01:59:21.527061Z","steps":["trace[1573770163] 'process raft request' (duration: 182.898892ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:21.532815Z","caller":"traceutil/trace.go:171","msg":"trace[562118826] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"186.446423ms","start":"2026-09-10T01:59:21.34635Z","end":"2026-09-10T01:59:21.532796Z","steps":["trace[562118826] 'process raft request' (duration: 185.986572ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:21.533001Z","caller":"traceutil/trace.go:171","msg":"trace[979911359] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"186.341971ms","start":"2026-09-10T01:59:21.346649Z","end":"2026-09-10T01:59:21.532991Z","steps":["trace[979911359] 'process raft request' (duration: 185.789067ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:30.908383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.950471ms","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-09-10T01:59:30.908464Z","caller":"traceutil/trace.go:171","msg":"trace[218288239] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5213; }","duration":"224.082134ms","start":"2026-09-10T01:59:30.684364Z","end":"2026-09-10T01:59:30.908447Z","steps":["trace[218288239] 'range keys from in-memory index tree' (duration: 223.87219ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:30.908494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.944367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T01:59:30.908551Z","caller":"traceutil/trace.go:171","msg":"trace[1231391892] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5213; }","duration":"137.044771ms","start":"2026-09-10T01:59:30.77149Z","end":"2026-09-10T01:59:30.908535Z","steps":["trace[1231391892] 'count revisions from in-memory index tree' (duration: 136.866276ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:53.713964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2882} {"level":"info","ts":"2026-09-10T01:59:53.803963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2882,"took":"88.73332ms","hash":297910557,"current-db-size-bytes":52420608,"current-db-size":"52 MB","current-db-size-in-use-bytes":27680768,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-10T01:59:53.804045Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":297910557,"revision":2882,"compact-revision":-1} {"level":"info","ts":"2026-09-10T02:04:53.724219Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5371} {"level":"info","ts":"2026-09-10T02:04:53.803096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5371,"took":"76.925497ms","hash":1281541814,"current-db-size-bytes":52420608,"current-db-size":"52 MB","current-db-size-in-use-bytes":18771968,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-10T02:04:53.803163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1281541814,"revision":5371,"compact-revision":2882}