{"level":"warn","ts":"2026-09-19T10:21:54.240055Z","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-19T10:21:54.240183Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.10: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.10:2380","--initial-cluster=instance=https://162.253.55.10:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.10:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.10: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-19T10:21:54.240276Z","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-19T10:21:54.24029Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-09-19T10:21:54.240311Z","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-19T10:21:54.241027Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"]} {"level":"info","ts":"2026-09-19T10:21:54.241106Z","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.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.10: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-19T10:21:54.246358Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.999585ms"} {"level":"info","ts":"2026-09-19T10:21:54.259767Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"373d5d49ce4adaff","cluster-id":"467836e907a15d60"} {"level":"info","ts":"2026-09-19T10:21:54.259867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T10:21:54.259896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 0"} {"level":"info","ts":"2026-09-19T10:21:54.259907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 373d5d49ce4adaff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T10:21:54.259916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 1"} {"level":"info","ts":"2026-09-19T10:21:54.259964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"warn","ts":"2026-09-19T10:21:54.269723Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T10:21:54.271508Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T10:21:54.272825Z","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-19T10:21:54.280328Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"373d5d49ce4adaff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T10:21:54.282634Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"373d5d49ce4adaff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T10:21:54.282633Z","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-19T10:21:54.282817Z","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-19T10:21:54.282854Z","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-19T10:21:54.285636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"info","ts":"2026-09-19T10:21:54.287759Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","added-peer-id":"373d5d49ce4adaff","added-peer-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-09-19T10:21:54.289673Z","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-19T10:21:54.289825Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-09-19T10:21:54.2899Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-09-19T10:21:54.289977Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"373d5d49ce4adaff","initial-advertise-peer-urls":["https://162.253.55.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T10:21:54.290045Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T10:21:54.861174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T10:21:54.861237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T10:21:54.86126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgPreVoteResp from 373d5d49ce4adaff at term 1"} {"level":"info","ts":"2026-09-19T10:21:54.861279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became candidate at term 2"} {"level":"info","ts":"2026-09-19T10:21:54.86129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgVoteResp from 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-09-19T10:21:54.861305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became leader at term 2"} {"level":"info","ts":"2026-09-19T10:21:54.861319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 373d5d49ce4adaff elected leader 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-09-19T10:21:54.86263Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"373d5d49ce4adaff","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.10:2379]}","request-path":"/0/members/373d5d49ce4adaff/attributes","cluster-id":"467836e907a15d60","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T10:21:54.862635Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T10:21:54.862781Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:21:54.862944Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T10:21:54.862973Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T10:21:54.863075Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T10:21:54.864054Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:21:54.864193Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:21:54.864282Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:21:54.86594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.10:2379"} {"level":"info","ts":"2026-09-19T10:21:54.866854Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-19T10:22:08.367731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.474826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:22:08.367841Z","caller":"traceutil/trace.go:171","msg":"trace[2123017003] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"130.122724ms","start":"2026-09-19T10:22:08.237704Z","end":"2026-09-19T10:22:08.367826Z","steps":["trace[2123017003] 'read index received' (duration: 24.381µs)","trace[2123017003] 'applied index is now lower than readState.Index' (duration: 130.097323ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:22:08.36796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.252289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-19T10:22:08.367936Z","caller":"traceutil/trace.go:171","msg":"trace[2076433185] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"215.397663ms","start":"2026-09-19T10:22:08.152491Z","end":"2026-09-19T10:22:08.367889Z","steps":["trace[2076433185] 'process raft request' (duration: 79.096461ms)","trace[2076433185] 'compare' (duration: 135.381723ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:22:08.367988Z","caller":"traceutil/trace.go:171","msg":"trace[359584178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:364; }","duration":"130.28119ms","start":"2026-09-19T10:22:08.237698Z","end":"2026-09-19T10:22:08.367979Z","steps":["trace[359584178] 'agreement among raft nodes before linearized reading' (duration: 130.177107ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:22:55.194138Z","caller":"traceutil/trace.go:171","msg":"trace[1991465512] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"159.883286ms","start":"2026-09-19T10:22:55.034225Z","end":"2026-09-19T10:22:55.194108Z","steps":["trace[1991465512] 'process raft request' (duration: 159.735411ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:22:55.194739Z","caller":"traceutil/trace.go:171","msg":"trace[354100357] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:560; }","duration":"157.602497ms","start":"2026-09-19T10:22:55.037125Z","end":"2026-09-19T10:22:55.194728Z","steps":["trace[354100357] 'read index received' (duration: 157.598457ms)","trace[354100357] 'applied index is now lower than readState.Index' (duration: 3.16µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:22:55.195009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.847886ms","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-09-19T10:22:55.2048Z","caller":"traceutil/trace.go:171","msg":"trace[1835720331] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"167.684173ms","start":"2026-09-19T10:22:55.037094Z","end":"2026-09-19T10:22:55.204778Z","steps":["trace[1835720331] 'agreement among raft nodes before linearized reading' (duration: 157.737982ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:22:55.208664Z","caller":"traceutil/trace.go:171","msg":"trace[248525106] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"168.665787ms","start":"2026-09-19T10:22:55.039702Z","end":"2026-09-19T10:22:55.208368Z","steps":["trace[248525106] 'process raft request' (duration: 168.231143ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:22:55.20893Z","caller":"traceutil/trace.go:171","msg":"trace[2120902166] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"146.839999ms","start":"2026-09-19T10:22:55.06207Z","end":"2026-09-19T10:22:55.20891Z","steps":["trace[2120902166] 'process raft request' (duration: 146.528127ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:22:55.209043Z","caller":"traceutil/trace.go:171","msg":"trace[1782970882] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"146.332131ms","start":"2026-09-19T10:22:55.06269Z","end":"2026-09-19T10:22:55.209022Z","steps":["trace[1782970882] 'process raft request' (duration: 145.947377ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:22:55.209196Z","caller":"traceutil/trace.go:171","msg":"trace[786893420] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"139.982883ms","start":"2026-09-19T10:22:55.069187Z","end":"2026-09-19T10:22:55.20917Z","steps":["trace[786893420] 'process raft request' (duration: 139.529616ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:22:55.209194Z","caller":"traceutil/trace.go:171","msg":"trace[132590855] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"140.19453ms","start":"2026-09-19T10:22:55.06899Z","end":"2026-09-19T10:22:55.209185Z","steps":["trace[132590855] 'process raft request' (duration: 139.681112ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:22:55.209318Z","caller":"traceutil/trace.go:171","msg":"trace[1998241889] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"120.409792ms","start":"2026-09-19T10:22:55.088881Z","end":"2026-09-19T10:22:55.209291Z","steps":["trace[1998241889] 'process raft request' (duration: 119.879823ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:22:55.209367Z","caller":"traceutil/trace.go:171","msg":"trace[1137764209] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"153.832978ms","start":"2026-09-19T10:22:55.055498Z","end":"2026-09-19T10:22:55.209331Z","steps":["trace[1137764209] 'process raft request' (duration: 152.978838ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:22:55.209706Z","caller":"traceutil/trace.go:171","msg":"trace[1646954345] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"150.679031ms","start":"2026-09-19T10:22:55.05902Z","end":"2026-09-19T10:22:55.2097Z","steps":["trace[1646954345] 'process raft request' (duration: 149.52721ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:22:55.210384Z","caller":"traceutil/trace.go:171","msg":"trace[2019858720] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"154.658816ms","start":"2026-09-19T10:22:55.055094Z","end":"2026-09-19T10:22:55.209753Z","steps":["trace[2019858720] 'process raft request' (duration: 153.099212ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:01.550407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.975333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:23:01.550601Z","caller":"traceutil/trace.go:171","msg":"trace[825104235] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"251.183926ms","start":"2026-09-19T10:23:01.2994Z","end":"2026-09-19T10:23:01.550584Z","steps":["trace[825104235] 'process raft request' (duration: 251.120053ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:01.550626Z","caller":"traceutil/trace.go:171","msg":"trace[399463204] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"298.630502ms","start":"2026-09-19T10:23:01.251974Z","end":"2026-09-19T10:23:01.550605Z","steps":["trace[399463204] 'process raft request' (duration: 117.38783ms)","trace[399463204] 'compare' (duration: 180.88617ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:23:01.598212Z","caller":"traceutil/trace.go:171","msg":"trace[436571537] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"151.88229ms","start":"2026-09-19T10:23:01.446304Z","end":"2026-09-19T10:23:01.598186Z","steps":["trace[436571537] 'process raft request' (duration: 151.398873ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:02.17099Z","caller":"traceutil/trace.go:171","msg":"trace[419462557] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"501.369352ms","start":"2026-09-19T10:23:01.669594Z","end":"2026-09-19T10:23:02.170963Z","steps":["trace[419462557] 'process raft request' (duration: 501.149824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:02.171106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.543832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:23:02.171144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:23:01.669569Z","time spent":"501.490636ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:23:02.171192Z","caller":"traceutil/trace.go:171","msg":"trace[462118996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"453.662937ms","start":"2026-09-19T10:23:01.717499Z","end":"2026-09-19T10:23:02.171162Z","steps":["trace[462118996] 'agreement among raft nodes before linearized reading' (duration: 453.511931ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:02.17123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:23:01.717481Z","time spent":"453.741279ms","remote":"127.0.0.1:41712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-19T10:23:02.171641Z","caller":"traceutil/trace.go:171","msg":"trace[1319042695] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"454.017808ms","start":"2026-09-19T10:23:01.71755Z","end":"2026-09-19T10:23:02.171568Z","steps":["trace[1319042695] 'read index received' (duration: 453.120728ms)","trace[1319042695] 'applied index is now lower than readState.Index' (duration: 819.428µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:23:02.171854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.621949ms","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-09-19T10:23:02.171892Z","caller":"traceutil/trace.go:171","msg":"trace[1532855842] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:649; }","duration":"174.701162ms","start":"2026-09-19T10:23:01.997179Z","end":"2026-09-19T10:23:02.17188Z","steps":["trace[1532855842] 'agreement among raft nodes before linearized reading' (duration: 174.63254ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:02.171958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.746064ms","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-19T10:23:02.171998Z","caller":"traceutil/trace.go:171","msg":"trace[409034915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"405.844527ms","start":"2026-09-19T10:23:01.766139Z","end":"2026-09-19T10:23:02.171983Z","steps":["trace[409034915] 'agreement among raft nodes before linearized reading' (duration: 405.65142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:02.172033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:23:01.766119Z","time spent":"405.906809ms","remote":"127.0.0.1:41998","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-09-19T10:23:02.172256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.358127ms","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-19T10:23:02.172288Z","caller":"traceutil/trace.go:171","msg":"trace[1977586804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"400.42712ms","start":"2026-09-19T10:23:01.771852Z","end":"2026-09-19T10:23:02.172279Z","steps":["trace[1977586804] 'agreement among raft nodes before linearized reading' (duration: 400.274065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:02.172312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:23:01.771833Z","time spent":"400.473331ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T10:24:14.041758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.592716ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:24:14.041882Z","caller":"traceutil/trace.go:171","msg":"trace[1517948671] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1492; }","duration":"249.736061ms","start":"2026-09-19T10:24:13.792129Z","end":"2026-09-19T10:24:14.041865Z","steps":["trace[1517948671] 'range keys from in-memory index tree' (duration: 249.557345ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:14.129153Z","caller":"traceutil/trace.go:171","msg":"trace[1492180660] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"402.138877ms","start":"2026-09-19T10:24:13.726994Z","end":"2026-09-19T10:24:14.129133Z","steps":["trace[1492180660] 'process raft request' (duration: 401.991082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:14.129278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:13.726976Z","time spent":"402.235951ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:24:14.915829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.744955ms","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-19T10:24:14.915942Z","caller":"traceutil/trace.go:171","msg":"trace[1784656270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1496; }","duration":"165.8826ms","start":"2026-09-19T10:24:14.750031Z","end":"2026-09-19T10:24:14.915914Z","steps":["trace[1784656270] 'range keys from in-memory index tree' (duration: 165.545368ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:31.846204Z","caller":"traceutil/trace.go:171","msg":"trace[24989237] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"157.831974ms","start":"2026-09-19T10:24:31.68834Z","end":"2026-09-19T10:24:31.846172Z","steps":["trace[24989237] 'process raft request' (duration: 113.463859ms)","trace[24989237] 'compare' (duration: 44.23408ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:24:31.858454Z","caller":"traceutil/trace.go:171","msg":"trace[294417766] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"168.073583ms","start":"2026-09-19T10:24:31.690344Z","end":"2026-09-19T10:24:31.858417Z","steps":["trace[294417766] 'process raft request' (duration: 167.918787ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:31.858752Z","caller":"traceutil/trace.go:171","msg":"trace[284204670] linearizableReadLoop","detail":"{readStateIndex:1858; appliedIndex:1850; }","duration":"141.082552ms","start":"2026-09-19T10:24:31.717631Z","end":"2026-09-19T10:24:31.858714Z","steps":["trace[284204670] 'read index received' (duration: 84.351853ms)","trace[284204670] 'applied index is now lower than readState.Index' (duration: 56.729829ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:24:31.858908Z","caller":"traceutil/trace.go:171","msg":"trace[893025825] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"168.018111ms","start":"2026-09-19T10:24:31.690877Z","end":"2026-09-19T10:24:31.858895Z","steps":["trace[893025825] 'process raft request' (duration: 167.484432ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:31.859066Z","caller":"traceutil/trace.go:171","msg":"trace[969501610] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"166.029299ms","start":"2026-09-19T10:24:31.693024Z","end":"2026-09-19T10:24:31.859053Z","steps":["trace[969501610] 'process raft request' (duration: 165.564223ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:31.859296Z","caller":"traceutil/trace.go:171","msg":"trace[515900387] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"164.534735ms","start":"2026-09-19T10:24:31.694749Z","end":"2026-09-19T10:24:31.859284Z","steps":["trace[515900387] 'process raft request' (duration: 163.871422ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:31.859482Z","caller":"traceutil/trace.go:171","msg":"trace[779011372] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"164.354618ms","start":"2026-09-19T10:24:31.695115Z","end":"2026-09-19T10:24:31.859469Z","steps":["trace[779011372] 'process raft request' (duration: 163.536859ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:31.859687Z","caller":"traceutil/trace.go:171","msg":"trace[1471382858] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"164.358608ms","start":"2026-09-19T10:24:31.695316Z","end":"2026-09-19T10:24:31.859675Z","steps":["trace[1471382858] 'process raft request' (duration: 163.368133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:31.859878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.239963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:24:31.859922Z","caller":"traceutil/trace.go:171","msg":"trace[1008364065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1788; }","duration":"142.338577ms","start":"2026-09-19T10:24:31.717573Z","end":"2026-09-19T10:24:31.859912Z","steps":["trace[1008364065] 'agreement among raft nodes before linearized reading' (duration: 142.263834ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:31.858678Z","caller":"traceutil/trace.go:171","msg":"trace[77096097] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"165.682217ms","start":"2026-09-19T10:24:31.692974Z","end":"2026-09-19T10:24:31.858656Z","steps":["trace[77096097] 'process raft request' (duration: 165.559493ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:41.463491Z","caller":"traceutil/trace.go:171","msg":"trace[1755797373] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"123.197988ms","start":"2026-09-19T10:24:41.340265Z","end":"2026-09-19T10:24:41.463463Z","steps":["trace[1755797373] 'process raft request' (duration: 122.99244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:41.692088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.748123ms","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-09-19T10:24:41.692198Z","caller":"traceutil/trace.go:171","msg":"trace[28620412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1911; }","duration":"124.912429ms","start":"2026-09-19T10:24:41.567256Z","end":"2026-09-19T10:24:41.692168Z","steps":["trace[28620412] 'range keys from in-memory index tree' (duration: 124.575627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:42.362646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.641389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:24:42.362844Z","caller":"traceutil/trace.go:171","msg":"trace[675915181] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"341.15107ms","start":"2026-09-19T10:24:42.021672Z","end":"2026-09-19T10:24:42.362824Z","steps":["trace[675915181] 'process raft request' (duration: 341.072147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:42.362921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:42.021646Z","time spent":"341.229353ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:24:42.36307Z","caller":"traceutil/trace.go:171","msg":"trace[710124216] linearizableReadLoop","detail":"{readStateIndex:1985; appliedIndex:1984; }","duration":"531.148104ms","start":"2026-09-19T10:24:41.8319Z","end":"2026-09-19T10:24:42.363048Z","steps":["trace[710124216] 'read index received' (duration: 123.958884ms)","trace[710124216] 'applied index is now lower than readState.Index' (duration: 407.18753ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:24:42.363152Z","caller":"traceutil/trace.go:171","msg":"trace[1209892201] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"601.939383ms","start":"2026-09-19T10:24:41.76117Z","end":"2026-09-19T10:24:42.36311Z","steps":["trace[1209892201] 'process raft request' (duration: 194.747194ms)","trace[1209892201] 'compare' (duration: 406.527124ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:24:42.363186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.274338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-31b00415-50af-45cc-9e3f-cf8a6b2de1a1\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-09-19T10:24:42.363233Z","caller":"traceutil/trace.go:171","msg":"trace[1813517846] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-31b00415-50af-45cc-9e3f-cf8a6b2de1a1; range_end:; response_count:1; response_revision:1914; }","duration":"531.345271ms","start":"2026-09-19T10:24:41.831875Z","end":"2026-09-19T10:24:42.36322Z","steps":["trace[1813517846] 'agreement among raft nodes before linearized reading' (duration: 531.259268ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:42.363262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:41.831862Z","time spent":"531.391752ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3768,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-31b00415-50af-45cc-9e3f-cf8a6b2de1a1\" "} {"level":"warn","ts":"2026-09-19T10:24:42.364203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:41.761155Z","time spent":"602.074208ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:24:42.849315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.632465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:24:42.849518Z","caller":"traceutil/trace.go:171","msg":"trace[1002290517] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"625.469163ms","start":"2026-09-19T10:24:42.22403Z","end":"2026-09-19T10:24:42.8495Z","steps":["trace[1002290517] 'process raft request' (duration: 383.567208ms)","trace[1002290517] 'compare' (duration: 241.324854ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:24:42.849585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:42.224013Z","time spent":"625.537704ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:24:42.849684Z","caller":"traceutil/trace.go:171","msg":"trace[2125801806] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1986; }","duration":"486.551137ms","start":"2026-09-19T10:24:42.363107Z","end":"2026-09-19T10:24:42.849659Z","steps":["trace[2125801806] 'read index received' (duration: 244.549149ms)","trace[2125801806] 'applied index is now lower than readState.Index' (duration: 242.000988ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:24:42.849725Z","caller":"traceutil/trace.go:171","msg":"trace[1331279645] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"520.795342ms","start":"2026-09-19T10:24:42.32891Z","end":"2026-09-19T10:24:42.849705Z","steps":["trace[1331279645] 'process raft request' (duration: 520.521462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:42.849916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.216699ms","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-19T10:24:42.84995Z","caller":"traceutil/trace.go:171","msg":"trace[743472212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1916; }","duration":"908.26358ms","start":"2026-09-19T10:24:41.941676Z","end":"2026-09-19T10:24:42.849939Z","steps":["trace[743472212] 'agreement among raft nodes before linearized reading' (duration: 908.131126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:42.849977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:41.9416Z","time spent":"908.369953ms","remote":"127.0.0.1:41998","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-09-19T10:24:42.849986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.769129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-09-19T10:24:42.850017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:42.328895Z","time spent":"520.995789ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:24:42.850072Z","caller":"traceutil/trace.go:171","msg":"trace[1069204723] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1916; }","duration":"616.917574ms","start":"2026-09-19T10:24:42.233131Z","end":"2026-09-19T10:24:42.850049Z","steps":["trace[1069204723] 'agreement among raft nodes before linearized reading' (duration: 616.585162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:42.850095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.84356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:24:42.85012Z","caller":"traceutil/trace.go:171","msg":"trace[1730572274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1916; }","duration":"131.906801ms","start":"2026-09-19T10:24:42.718205Z","end":"2026-09-19T10:24:42.850111Z","steps":["trace[1730572274] 'agreement among raft nodes before linearized reading' (duration: 131.87084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:42.85013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:42.233104Z","time spent":"617.012428ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":550,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-19T10:24:42.850147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.867614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:24:42.850197Z","caller":"traceutil/trace.go:171","msg":"trace[1547137039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1916; }","duration":"296.916897ms","start":"2026-09-19T10:24:42.553263Z","end":"2026-09-19T10:24:42.85018Z","steps":["trace[1547137039] 'agreement among raft nodes before linearized reading' (duration: 296.840663ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:42.850169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.677706ms","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-09-19T10:24:42.850256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.848629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-19T10:24:42.85027Z","caller":"traceutil/trace.go:171","msg":"trace[283690083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1916; }","duration":"475.820781ms","start":"2026-09-19T10:24:42.374428Z","end":"2026-09-19T10:24:42.850249Z","steps":["trace[283690083] 'agreement among raft nodes before linearized reading' (duration: 475.557961ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:42.850294Z","caller":"traceutil/trace.go:171","msg":"trace[2080316684] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1916; }","duration":"393.933203ms","start":"2026-09-19T10:24:42.456349Z","end":"2026-09-19T10:24:42.850282Z","steps":["trace[2080316684] 'agreement among raft nodes before linearized reading' (duration: 393.805718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:42.85031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:42.374416Z","time spent":"475.885224ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-19T10:24:42.850339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:42.456325Z","time spent":"394.007124ms","remote":"127.0.0.1:41998","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-19T10:24:42.850453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.853295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T10:24:42.850547Z","caller":"traceutil/trace.go:171","msg":"trace[183045318] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1916; }","duration":"785.091014ms","start":"2026-09-19T10:24:42.065435Z","end":"2026-09-19T10:24:42.850526Z","steps":["trace[183045318] 'agreement among raft nodes before linearized reading' (duration: 784.284024ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:42.850744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:42.065378Z","time spent":"785.353474ms","remote":"127.0.0.1:49362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-19T10:24:43.586598Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15780508336561251209,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T10:24:43.632247Z","caller":"traceutil/trace.go:171","msg":"trace[699352751] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"777.153172ms","start":"2026-09-19T10:24:42.855068Z","end":"2026-09-19T10:24:43.632221Z","steps":["trace[699352751] 'process raft request' (duration: 776.972415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:43.632399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:42.855048Z","time spent":"777.269225ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:24:43.659397Z","caller":"traceutil/trace.go:171","msg":"trace[955600629] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"803.910695ms","start":"2026-09-19T10:24:42.855459Z","end":"2026-09-19T10:24:43.659369Z","steps":["trace[955600629] 'process raft request' (duration: 803.735999ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:43.659506Z","caller":"traceutil/trace.go:171","msg":"trace[493676221] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"803.613164ms","start":"2026-09-19T10:24:42.855877Z","end":"2026-09-19T10:24:43.659491Z","steps":["trace[493676221] 'process raft request' (duration: 803.417347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:43.659534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:42.855435Z","time spent":"804.016489ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:24:43.659512Z","caller":"traceutil/trace.go:171","msg":"trace[2043040241] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"804.191185ms","start":"2026-09-19T10:24:42.855282Z","end":"2026-09-19T10:24:43.659474Z","steps":["trace[2043040241] 'process raft request' (duration: 803.77444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:43.659584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:42.855862Z","time spent":"803.680725ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:24:43.659672Z","caller":"traceutil/trace.go:171","msg":"trace[1549724145] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"804.153074ms","start":"2026-09-19T10:24:42.85551Z","end":"2026-09-19T10:24:43.659663Z","steps":["trace[1549724145] 'process raft request' (duration: 803.746319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:43.659715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:42.855491Z","time spent":"804.198406ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:24:43.659715Z","caller":"traceutil/trace.go:171","msg":"trace[1439254829] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"392.792873ms","start":"2026-09-19T10:24:43.266899Z","end":"2026-09-19T10:24:43.659692Z","steps":["trace[1439254829] 'process raft request' (duration: 392.43126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:43.659874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:43.266877Z","time spent":"392.880197ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:24:43.660332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:42.855252Z","time spent":"804.33561ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:24:43.679606Z","caller":"traceutil/trace.go:171","msg":"trace[48707594] linearizableReadLoop","detail":"{readStateIndex:1995; appliedIndex:1988; }","duration":"593.954971ms","start":"2026-09-19T10:24:43.085626Z","end":"2026-09-19T10:24:43.679581Z","steps":["trace[48707594] 'read index received' (duration: 546.404488ms)","trace[48707594] 'applied index is now lower than readState.Index' (duration: 47.549103ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:24:43.679869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.22999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T10:24:43.67998Z","caller":"traceutil/trace.go:171","msg":"trace[1824705782] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1922; }","duration":"594.385756ms","start":"2026-09-19T10:24:43.085578Z","end":"2026-09-19T10:24:43.679964Z","steps":["trace[1824705782] 'agreement among raft nodes before linearized reading' (duration: 594.21933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:43.680165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:43.085563Z","time spent":"594.593854ms","remote":"127.0.0.1:42012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-19T10:24:43.680237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.666173ms","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-09-19T10:24:43.680318Z","caller":"traceutil/trace.go:171","msg":"trace[1139952711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1922; }","duration":"590.794527ms","start":"2026-09-19T10:24:43.089505Z","end":"2026-09-19T10:24:43.680299Z","steps":["trace[1139952711] 'agreement among raft nodes before linearized reading' (duration: 590.500906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:43.680377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:43.089485Z","time spent":"590.87846ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-19T10:24:43.680101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.274037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-19T10:24:43.680202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.784529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-19T10:24:43.680683Z","caller":"traceutil/trace.go:171","msg":"trace[1250887522] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1922; }","duration":"117.322378ms","start":"2026-09-19T10:24:43.563319Z","end":"2026-09-19T10:24:43.680641Z","steps":["trace[1250887522] 'agreement among raft nodes before linearized reading' (duration: 116.729167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:43.680583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.869673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-31b00415-50af-45cc-9e3f-cf8a6b2de1a1\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-09-19T10:24:43.680821Z","caller":"traceutil/trace.go:171","msg":"trace[225681870] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-31b00415-50af-45cc-9e3f-cf8a6b2de1a1; range_end:; response_count:1; response_revision:1922; }","duration":"313.290408ms","start":"2026-09-19T10:24:43.367468Z","end":"2026-09-19T10:24:43.680759Z","steps":["trace[225681870] 'agreement among raft nodes before linearized reading' (duration: 312.631585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:43.680887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:43.367455Z","time spent":"313.414283ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3768,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-31b00415-50af-45cc-9e3f-cf8a6b2de1a1\" "} {"level":"info","ts":"2026-09-19T10:24:43.680512Z","caller":"traceutil/trace.go:171","msg":"trace[402534897] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1922; }","duration":"482.726223ms","start":"2026-09-19T10:24:43.197749Z","end":"2026-09-19T10:24:43.680475Z","steps":["trace[402534897] 'agreement among raft nodes before linearized reading' (duration: 482.304018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:43.681282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:43.197723Z","time spent":"483.245842ms","remote":"127.0.0.1:49436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-09-19T10:24:55.103126Z","caller":"traceutil/trace.go:171","msg":"trace[2029596247] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"182.507637ms","start":"2026-09-19T10:24:54.920598Z","end":"2026-09-19T10:24:55.103106Z","steps":["trace[2029596247] 'process raft request' (duration: 181.853464ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:55.117606Z","caller":"traceutil/trace.go:171","msg":"trace[1342895137] linearizableReadLoop","detail":"{readStateIndex:2094; appliedIndex:2092; }","duration":"155.885788ms","start":"2026-09-19T10:24:54.961696Z","end":"2026-09-19T10:24:55.117581Z","steps":["trace[1342895137] 'read index received' (duration: 140.767921ms)","trace[1342895137] 'applied index is now lower than readState.Index' (duration: 15.116537ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:24:55.117735Z","caller":"traceutil/trace.go:171","msg":"trace[2014525868] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"176.18245ms","start":"2026-09-19T10:24:54.941538Z","end":"2026-09-19T10:24:55.117721Z","steps":["trace[2014525868] 'process raft request' (duration: 175.869267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:55.118081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.355494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T10:24:55.118178Z","caller":"traceutil/trace.go:171","msg":"trace[1667100612] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2018; }","duration":"156.493779ms","start":"2026-09-19T10:24:54.961663Z","end":"2026-09-19T10:24:55.118157Z","steps":["trace[1667100612] 'agreement among raft nodes before linearized reading' (duration: 156.24088ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:57.919365Z","caller":"traceutil/trace.go:171","msg":"trace[185216924] linearizableReadLoop","detail":"{readStateIndex:2115; appliedIndex:2114; }","duration":"170.257058ms","start":"2026-09-19T10:24:57.749066Z","end":"2026-09-19T10:24:57.919323Z","steps":["trace[185216924] 'read index received' (duration: 168.439572ms)","trace[185216924] 'applied index is now lower than readState.Index' (duration: 1.816686ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:24:57.91959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.506997ms","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-09-19T10:24:57.919617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.583587ms","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-19T10:24:57.919637Z","caller":"traceutil/trace.go:171","msg":"trace[290440840] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2039; }","duration":"170.572499ms","start":"2026-09-19T10:24:57.749053Z","end":"2026-09-19T10:24:57.919626Z","steps":["trace[290440840] 'agreement among raft nodes before linearized reading' (duration: 170.396352ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:57.919648Z","caller":"traceutil/trace.go:171","msg":"trace[1146925279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2039; }","duration":"168.640779ms","start":"2026-09-19T10:24:57.750996Z","end":"2026-09-19T10:24:57.919637Z","steps":["trace[1146925279] 'agreement among raft nodes before linearized reading' (duration: 168.460343ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:57.919758Z","caller":"traceutil/trace.go:171","msg":"trace[211692968] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"171.71692ms","start":"2026-09-19T10:24:57.748034Z","end":"2026-09-19T10:24:57.919751Z","steps":["trace[211692968] 'process raft request' (duration: 169.50886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:57.919842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.857372ms","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-19T10:24:57.919854Z","caller":"traceutil/trace.go:171","msg":"trace[1978861732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2039; }","duration":"164.898724ms","start":"2026-09-19T10:24:57.754951Z","end":"2026-09-19T10:24:57.919849Z","steps":["trace[1978861732] 'agreement among raft nodes before linearized reading' (duration: 164.850952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:57.919891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.89174ms","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-09-19T10:24:57.919954Z","caller":"traceutil/trace.go:171","msg":"trace[1024329351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2039; }","duration":"111.974813ms","start":"2026-09-19T10:24:57.807963Z","end":"2026-09-19T10:24:57.919938Z","steps":["trace[1024329351] 'agreement among raft nodes before linearized reading' (duration: 111.785206ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:25:22.158838Z","caller":"traceutil/trace.go:171","msg":"trace[358875906] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2256; }","duration":"237.678895ms","start":"2026-09-19T10:25:21.921064Z","end":"2026-09-19T10:25:22.158743Z","steps":["trace[358875906] 'read index received' (duration: 237.504049ms)","trace[358875906] 'applied index is now lower than readState.Index' (duration: 173.146µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:25:22.159009Z","caller":"traceutil/trace.go:171","msg":"trace[870259603] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"328.252864ms","start":"2026-09-19T10:25:21.830738Z","end":"2026-09-19T10:25:22.158991Z","steps":["trace[870259603] 'process raft request' (duration: 327.86161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:25:22.159252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.977919ms","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-09-19T10:25:22.159379Z","caller":"traceutil/trace.go:171","msg":"trace[1931249978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2175; }","duration":"140.172006ms","start":"2026-09-19T10:25:22.019164Z","end":"2026-09-19T10:25:22.159336Z","steps":["trace[1931249978] 'agreement among raft nodes before linearized reading' (duration: 139.906786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:25:22.159377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.279537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:25:22.159583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.051547ms","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-09-19T10:25:22.159631Z","caller":"traceutil/trace.go:171","msg":"trace[858208845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2175; }","duration":"128.14058ms","start":"2026-09-19T10:25:22.031477Z","end":"2026-09-19T10:25:22.159617Z","steps":["trace[858208845] 'agreement among raft nodes before linearized reading' (duration: 127.944072ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:25:22.159819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.503581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-19T10:25:22.159771Z","caller":"traceutil/trace.go:171","msg":"trace[219823582] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2175; }","duration":"238.557038ms","start":"2026-09-19T10:25:21.921003Z","end":"2026-09-19T10:25:22.15956Z","steps":["trace[219823582] 'agreement among raft nodes before linearized reading' (duration: 238.275077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:25:22.159904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.321833ms","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-19T10:25:22.160039Z","caller":"traceutil/trace.go:171","msg":"trace[118420356] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2175; }","duration":"126.677018ms","start":"2026-09-19T10:25:22.033244Z","end":"2026-09-19T10:25:22.159921Z","steps":["trace[118420356] 'agreement among raft nodes before linearized reading' (duration: 126.127917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:25:22.159728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.139453ms","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-09-19T10:25:22.160272Z","caller":"traceutil/trace.go:171","msg":"trace[1198150814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2175; }","duration":"101.711123ms","start":"2026-09-19T10:25:22.058534Z","end":"2026-09-19T10:25:22.160245Z","steps":["trace[1198150814] 'agreement among raft nodes before linearized reading' (duration: 100.751968ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:25:22.160295Z","caller":"traceutil/trace.go:171","msg":"trace[338638843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2175; }","duration":"108.623273ms","start":"2026-09-19T10:25:22.051522Z","end":"2026-09-19T10:25:22.160145Z","steps":["trace[338638843] 'agreement among raft nodes before linearized reading' (duration: 107.622356ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:25:22.160412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:25:21.830719Z","time spent":"328.475583ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:25:22.469509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.441654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:25:22.469767Z","caller":"traceutil/trace.go:171","msg":"trace[2087704022] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"305.140648ms","start":"2026-09-19T10:25:22.164584Z","end":"2026-09-19T10:25:22.469725Z","steps":["trace[2087704022] 'process raft request' (duration: 140.375292ms)","trace[2087704022] 'compare' (duration: 164.224197ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:25:22.47011Z","caller":"traceutil/trace.go:171","msg":"trace[1976124297] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"305.331605ms","start":"2026-09-19T10:25:22.164746Z","end":"2026-09-19T10:25:22.470077Z","steps":["trace[1976124297] 'process raft request' (duration: 304.890089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:25:22.470153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:25:22.164567Z","time spent":"305.320465ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:25:22.470289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:25:22.164719Z","time spent":"305.471ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:25:22.470511Z","caller":"traceutil/trace.go:171","msg":"trace[147740994] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"305.385308ms","start":"2026-09-19T10:25:22.165107Z","end":"2026-09-19T10:25:22.470492Z","steps":["trace[147740994] 'process raft request' (duration: 304.940831ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:25:22.470537Z","caller":"traceutil/trace.go:171","msg":"trace[1418259481] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"304.950532ms","start":"2026-09-19T10:25:22.165564Z","end":"2026-09-19T10:25:22.470515Z","steps":["trace[1418259481] 'process raft request' (duration: 304.597168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:25:22.470587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:25:22.165089Z","time spent":"305.460261ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:25:22.470657Z","caller":"traceutil/trace.go:171","msg":"trace[1774229957] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"304.05623ms","start":"2026-09-19T10:25:22.166543Z","end":"2026-09-19T10:25:22.470599Z","steps":["trace[1774229957] 'process raft request' (duration: 303.890103ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:25:22.470535Z","caller":"traceutil/trace.go:171","msg":"trace[1045253706] linearizableReadLoop","detail":"{readStateIndex:2262; appliedIndex:2257; }","duration":"121.946565ms","start":"2026-09-19T10:25:22.348564Z","end":"2026-09-19T10:25:22.470511Z","steps":["trace[1045253706] 'read index received' (duration: 59.920949ms)","trace[1045253706] 'applied index is now lower than readState.Index' (duration: 62.024066ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:25:22.470883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:25:22.166525Z","time spent":"304.230155ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:25:22.470968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:25:22.16555Z","time spent":"305.059985ms","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:25:22.471193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.487825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T10:25:22.471255Z","caller":"traceutil/trace.go:171","msg":"trace[465680961] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2180; }","duration":"122.739894ms","start":"2026-09-19T10:25:22.348494Z","end":"2026-09-19T10:25:22.471234Z","steps":["trace[465680961] 'agreement among raft nodes before linearized reading' (duration: 122.274378ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:25:22.741719Z","caller":"traceutil/trace.go:171","msg":"trace[909712649] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"212.829036ms","start":"2026-09-19T10:25:22.528854Z","end":"2026-09-19T10:25:22.741683Z","steps":["trace[909712649] 'process raft request' (duration: 210.908268ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:06.477877Z","caller":"traceutil/trace.go:171","msg":"trace[1656301120] transaction","detail":"{read_only:false; response_revision:4380; number_of_response:1; }","duration":"100.317064ms","start":"2026-09-19T10:31:06.377528Z","end":"2026-09-19T10:31:06.477845Z","steps":["trace[1656301120] 'process raft request' (duration: 83.206432ms)","trace[1656301120] 'compare' (duration: 16.832403ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:06.478328Z","caller":"traceutil/trace.go:171","msg":"trace[1666595989] transaction","detail":"{read_only:false; response_revision:4381; number_of_response:1; }","duration":"100.285293ms","start":"2026-09-19T10:31:06.377614Z","end":"2026-09-19T10:31:06.477899Z","steps":["trace[1666595989] 'process raft request' (duration: 100.156369ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:10.870597Z","caller":"traceutil/trace.go:171","msg":"trace[1547524784] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"233.03806ms","start":"2026-09-19T10:31:10.637537Z","end":"2026-09-19T10:31:10.870575Z","steps":["trace[1547524784] 'process raft request' (duration: 232.839594ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:10.871197Z","caller":"traceutil/trace.go:171","msg":"trace[520401479] linearizableReadLoop","detail":"{readStateIndex:4906; appliedIndex:4904; }","duration":"162.810599ms","start":"2026-09-19T10:31:10.708368Z","end":"2026-09-19T10:31:10.871178Z","steps":["trace[520401479] 'read index received' (duration: 161.962521ms)","trace[520401479] 'applied index is now lower than readState.Index' (duration: 846.668µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:10.871342Z","caller":"traceutil/trace.go:171","msg":"trace[1007496649] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"172.052749ms","start":"2026-09-19T10:31:10.699264Z","end":"2026-09-19T10:31:10.871317Z","steps":["trace[1007496649] 'process raft request' (duration: 171.829381ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:10.871764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.211524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T10:31:10.871878Z","caller":"traceutil/trace.go:171","msg":"trace[1734311610] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:4727; }","duration":"120.412461ms","start":"2026-09-19T10:31:10.75144Z","end":"2026-09-19T10:31:10.871853Z","steps":["trace[1734311610] 'agreement among raft nodes before linearized reading' (duration: 120.192913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:10.873043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.652102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-09-19T10:31:10.873136Z","caller":"traceutil/trace.go:171","msg":"trace[1972541796] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4727; }","duration":"164.784906ms","start":"2026-09-19T10:31:10.708335Z","end":"2026-09-19T10:31:10.87312Z","steps":["trace[1972541796] 'agreement among raft nodes before linearized reading' (duration: 162.988926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:12.765235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.435816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:31:12.765361Z","caller":"traceutil/trace.go:171","msg":"trace[787337521] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"166.869619ms","start":"2026-09-19T10:31:12.598467Z","end":"2026-09-19T10:31:12.765337Z","steps":["trace[787337521] 'process raft request' (duration: 31.240506ms)","trace[787337521] 'compare' (duration: 135.285541ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:12.784215Z","caller":"traceutil/trace.go:171","msg":"trace[1477246059] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"177.838045ms","start":"2026-09-19T10:31:12.606359Z","end":"2026-09-19T10:31:12.784197Z","steps":["trace[1477246059] 'process raft request' (duration: 177.787983ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:12.784261Z","caller":"traceutil/trace.go:171","msg":"trace[1664236527] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"179.77409ms","start":"2026-09-19T10:31:12.60446Z","end":"2026-09-19T10:31:12.784234Z","steps":["trace[1664236527] 'process raft request' (duration: 179.526442ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:17.38256Z","caller":"traceutil/trace.go:171","msg":"trace[625277651] linearizableReadLoop","detail":"{readStateIndex:5040; appliedIndex:5039; }","duration":"284.754589ms","start":"2026-09-19T10:31:17.097774Z","end":"2026-09-19T10:31:17.382529Z","steps":["trace[625277651] 'read index received' (duration: 212.810206ms)","trace[625277651] 'applied index is now lower than readState.Index' (duration: 71.943003ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:31:17.382795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.998257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-09-19T10:31:17.382792Z","caller":"traceutil/trace.go:171","msg":"trace[1013401565] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"286.648461ms","start":"2026-09-19T10:31:17.096117Z","end":"2026-09-19T10:31:17.382766Z","steps":["trace[1013401565] 'process raft request' (duration: 214.557264ms)","trace[1013401565] 'compare' (duration: 71.649163ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:17.382845Z","caller":"traceutil/trace.go:171","msg":"trace[734265927] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4855; }","duration":"285.09071ms","start":"2026-09-19T10:31:17.097741Z","end":"2026-09-19T10:31:17.382832Z","steps":["trace[734265927] 'agreement among raft nodes before linearized reading' (duration: 284.909684ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:23.850708Z","caller":"traceutil/trace.go:171","msg":"trace[1235513975] linearizableReadLoop","detail":"{readStateIndex:5101; appliedIndex:5100; }","duration":"131.021ms","start":"2026-09-19T10:31:23.719663Z","end":"2026-09-19T10:31:23.850684Z","steps":["trace[1235513975] 'read index received' (duration: 130.841984ms)","trace[1235513975] 'applied index is now lower than readState.Index' (duration: 177.846µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:23.850768Z","caller":"traceutil/trace.go:171","msg":"trace[1036887748] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"131.821197ms","start":"2026-09-19T10:31:23.71892Z","end":"2026-09-19T10:31:23.850741Z","steps":["trace[1036887748] 'process raft request' (duration: 131.627731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:23.850899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.209476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:23.851243Z","caller":"traceutil/trace.go:171","msg":"trace[564310178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4913; }","duration":"131.607729ms","start":"2026-09-19T10:31:23.719618Z","end":"2026-09-19T10:31:23.851226Z","steps":["trace[564310178] 'agreement among raft nodes before linearized reading' (duration: 131.216406ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:23.900309Z","caller":"traceutil/trace.go:171","msg":"trace[324355309] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"180.245002ms","start":"2026-09-19T10:31:23.720048Z","end":"2026-09-19T10:31:23.900293Z","steps":["trace[324355309] 'process raft request' (duration: 180.150579ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:23.900304Z","caller":"traceutil/trace.go:171","msg":"trace[514727664] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"180.45778ms","start":"2026-09-19T10:31:23.719817Z","end":"2026-09-19T10:31:23.900275Z","steps":["trace[514727664] 'process raft request' (duration: 180.238513ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:23.900618Z","caller":"traceutil/trace.go:171","msg":"trace[1734965979] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"178.215185ms","start":"2026-09-19T10:31:23.722388Z","end":"2026-09-19T10:31:23.900603Z","steps":["trace[1734965979] 'process raft request' (duration: 177.863123ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:23.900939Z","caller":"traceutil/trace.go:171","msg":"trace[708940018] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"172.263856ms","start":"2026-09-19T10:31:23.728664Z","end":"2026-09-19T10:31:23.900928Z","steps":["trace[708940018] 'process raft request' (duration: 171.810139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:23.901347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.282095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-19T10:31:23.90144Z","caller":"traceutil/trace.go:171","msg":"trace[1094392439] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4917; }","duration":"180.391768ms","start":"2026-09-19T10:31:23.721014Z","end":"2026-09-19T10:31:23.901406Z","steps":["trace[1094392439] 'agreement among raft nodes before linearized reading' (duration: 179.912563ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:24.0238Z","caller":"traceutil/trace.go:171","msg":"trace[1849801929] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"112.534609ms","start":"2026-09-19T10:31:23.911238Z","end":"2026-09-19T10:31:24.023773Z","steps":["trace[1849801929] 'process raft request' (duration: 112.297171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:24.024038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.198472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-af45a1f5-54b5-47b6-adf4-5069bd5dcc65\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-19T10:31:24.023798Z","caller":"traceutil/trace.go:171","msg":"trace[805228936] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5105; }","duration":"109.962282ms","start":"2026-09-19T10:31:23.913814Z","end":"2026-09-19T10:31:24.023777Z","steps":["trace[805228936] 'read index received' (duration: 62.762078ms)","trace[805228936] 'applied index is now lower than readState.Index' (duration: 47.199404ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:24.024054Z","caller":"traceutil/trace.go:171","msg":"trace[208863821] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"111.837795ms","start":"2026-09-19T10:31:23.912187Z","end":"2026-09-19T10:31:24.024025Z","steps":["trace[208863821] 'process raft request' (duration: 111.490503ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:24.024083Z","caller":"traceutil/trace.go:171","msg":"trace[1085959926] range","detail":"{range_begin:/registry/persistentvolumes/pvc-af45a1f5-54b5-47b6-adf4-5069bd5dcc65; range_end:; response_count:1; response_revision:4921; }","duration":"110.290945ms","start":"2026-09-19T10:31:23.913778Z","end":"2026-09-19T10:31:24.024069Z","steps":["trace[1085959926] 'agreement among raft nodes before linearized reading' (duration: 110.065416ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:24.024195Z","caller":"traceutil/trace.go:171","msg":"trace[1100881076] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"111.948759ms","start":"2026-09-19T10:31:23.912219Z","end":"2026-09-19T10:31:24.024168Z","steps":["trace[1100881076] 'process raft request' (duration: 111.513774ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:28.436162Z","caller":"traceutil/trace.go:171","msg":"trace[916753274] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"259.96562ms","start":"2026-09-19T10:31:28.17595Z","end":"2026-09-19T10:31:28.435916Z","steps":["trace[916753274] 'process raft request' (duration: 182.61889ms)","trace[916753274] 'compare' (duration: 76.981758ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:28.522289Z","caller":"traceutil/trace.go:171","msg":"trace[1619333156] linearizableReadLoop","detail":"{readStateIndex:5201; appliedIndex:5199; }","duration":"305.705568ms","start":"2026-09-19T10:31:28.216563Z","end":"2026-09-19T10:31:28.522268Z","steps":["trace[1619333156] 'read index received' (duration: 142.017676ms)","trace[1619333156] 'applied index is now lower than readState.Index' (duration: 163.686562ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:28.522331Z","caller":"traceutil/trace.go:171","msg":"trace[2019640738] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"324.697386ms","start":"2026-09-19T10:31:28.197604Z","end":"2026-09-19T10:31:28.522301Z","steps":["trace[2019640738] 'process raft request' (duration: 324.51645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:28.52249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:28.197589Z","time spent":"324.816151ms","remote":"127.0.0.1:41754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:31:28.522554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.972467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-19T10:31:28.5226Z","caller":"traceutil/trace.go:171","msg":"trace[508422360] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5012; }","duration":"306.05782ms","start":"2026-09-19T10:31:28.216528Z","end":"2026-09-19T10:31:28.522586Z","steps":["trace[508422360] 'agreement among raft nodes before linearized reading' (duration: 305.852723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:28.522631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:28.216514Z","time spent":"306.108681ms","remote":"127.0.0.1:41998","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-19T10:31:28.523153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.710477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c0d59d05-b1fc-48e7-941a-cfdc9048d905\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-19T10:31:28.523318Z","caller":"traceutil/trace.go:171","msg":"trace[1841205522] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c0d59d05-b1fc-48e7-941a-cfdc9048d905; range_end:; response_count:1; response_revision:5012; }","duration":"117.952715ms","start":"2026-09-19T10:31:28.40534Z","end":"2026-09-19T10:31:28.523293Z","steps":["trace[1841205522] 'agreement among raft nodes before linearized reading' (duration: 117.177988ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:29.512035Z","caller":"traceutil/trace.go:171","msg":"trace[1366954486] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"101.357219ms","start":"2026-09-19T10:31:29.410665Z","end":"2026-09-19T10:31:29.512022Z","steps":["trace[1366954486] 'process raft request' (duration: 101.245345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:29.512203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.419049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-09-19T10:31:29.512232Z","caller":"traceutil/trace.go:171","msg":"trace[1666620002] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:5053; }","duration":"106.46172ms","start":"2026-09-19T10:31:29.405758Z","end":"2026-09-19T10:31:29.512219Z","steps":["trace[1666620002] 'agreement among raft nodes before linearized reading' (duration: 106.380927ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:29.659829Z","caller":"traceutil/trace.go:171","msg":"trace[1736958709] linearizableReadLoop","detail":"{readStateIndex:5245; appliedIndex:5244; }","duration":"104.827815ms","start":"2026-09-19T10:31:29.554982Z","end":"2026-09-19T10:31:29.65981Z","steps":["trace[1736958709] 'read index received' (duration: 61.955364ms)","trace[1736958709] 'applied index is now lower than readState.Index' (duration: 42.871481ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:29.659921Z","caller":"traceutil/trace.go:171","msg":"trace[1778137241] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"136.307874ms","start":"2026-09-19T10:31:29.523575Z","end":"2026-09-19T10:31:29.659883Z","steps":["trace[1778137241] 'process raft request' (duration: 93.485564ms)","trace[1778137241] 'compare' (duration: 42.641384ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:31:29.660028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.623591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18d6b23f6cf06521\" ","response":"range_response_count:1 size:740"} {"level":"warn","ts":"2026-09-19T10:31:29.66003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.798947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-09-19T10:31:29.660056Z","caller":"traceutil/trace.go:171","msg":"trace[216279713] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18d6b23f6cf06521; range_end:; response_count:1; response_revision:5055; }","duration":"102.692623ms","start":"2026-09-19T10:31:29.557353Z","end":"2026-09-19T10:31:29.660046Z","steps":["trace[216279713] 'agreement among raft nodes before linearized reading' (duration: 102.562549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:29.660066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.063834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c0d59d05-b1fc-48e7-941a-cfdc9048d905\" ","response":"range_response_count:1 size:3938"} {"level":"info","ts":"2026-09-19T10:31:29.660075Z","caller":"traceutil/trace.go:171","msg":"trace[1873678192] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5055; }","duration":"138.847059ms","start":"2026-09-19T10:31:29.521215Z","end":"2026-09-19T10:31:29.660062Z","steps":["trace[1873678192] 'agreement among raft nodes before linearized reading' (duration: 138.698914ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:29.660128Z","caller":"traceutil/trace.go:171","msg":"trace[966827558] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c0d59d05-b1fc-48e7-941a-cfdc9048d905; range_end:; response_count:1; response_revision:5055; }","duration":"133.146116ms","start":"2026-09-19T10:31:29.526969Z","end":"2026-09-19T10:31:29.660115Z","steps":["trace[966827558] 'agreement among raft nodes before linearized reading' (duration: 132.917669ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:29.667881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.488017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-09-19T10:31:29.667959Z","caller":"traceutil/trace.go:171","msg":"trace[919766986] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5055; }","duration":"105.57145ms","start":"2026-09-19T10:31:29.562363Z","end":"2026-09-19T10:31:29.667935Z","steps":["trace[919766986] 'agreement among raft nodes before linearized reading' (duration: 97.645723ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:54.910771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2783} {"level":"info","ts":"2026-09-19T10:31:55.025641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2783,"took":"113.445854ms","hash":3605972575,"current-db-size-bytes":52166656,"current-db-size":"52 MB","current-db-size-in-use-bytes":27553792,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T10:31:55.025707Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3605972575,"revision":2783,"compact-revision":-1} {"level":"warn","ts":"2026-09-19T10:35:30.047827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.298374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:4 size:218498"} {"level":"info","ts":"2026-09-19T10:35:30.047936Z","caller":"traceutil/trace.go:171","msg":"trace[234447187] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:4; response_revision:6858; }","duration":"100.444259ms","start":"2026-09-19T10:35:29.947476Z","end":"2026-09-19T10:35:30.04792Z","steps":["trace[234447187] 'range keys from in-memory index tree' (duration: 100.13964ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:36:54.918674Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5272} {"level":"info","ts":"2026-09-19T10:36:55.03119Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5272,"took":"111.448408ms","hash":4011293138,"current-db-size-bytes":52166656,"current-db-size":"52 MB","current-db-size-in-use-bytes":18649088,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T10:36:55.031259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4011293138,"revision":5272,"compact-revision":2783}