{"level":"warn","ts":"2026-09-01T18:22:17.762595Z","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-01T18:22:17.76269Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.144: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.144:2380","--initial-cluster=instance=https://162.253.55.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.144: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-01T18:22:17.762874Z","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-01T18:22:17.762895Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.144:2380"]} {"level":"info","ts":"2026-09-01T18:22:17.762916Z","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-01T18:22:17.763765Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.144:2379"]} {"level":"info","ts":"2026-09-01T18:22:17.763906Z","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.144:2380"],"listen-peer-urls":["https://162.253.55.144:2380"],"advertise-client-urls":["https://162.253.55.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.144: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-01T18:22:17.777231Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.053134ms"} {"level":"info","ts":"2026-09-01T18:22:17.783712Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"873be3731fd7a3eb","cluster-id":"9ca43c00a45c1826"} {"level":"info","ts":"2026-09-01T18:22:17.783783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T18:22:17.783807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became follower at term 0"} {"level":"info","ts":"2026-09-01T18:22:17.78382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 873be3731fd7a3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T18:22:17.783834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became follower at term 1"} {"level":"info","ts":"2026-09-01T18:22:17.78387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb switched to configuration voters=(9744632302341170155)"} {"level":"warn","ts":"2026-09-01T18:22:17.79296Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T18:22:17.794105Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T18:22:17.795344Z","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-01T18:22:17.797355Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"873be3731fd7a3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T18:22:17.797554Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"873be3731fd7a3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T18:22:17.797776Z","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-01T18:22:17.79783Z","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-01T18:22:17.79784Z","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-01T18:22:17.801097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb switched to configuration voters=(9744632302341170155)"} {"level":"info","ts":"2026-09-01T18:22:17.801304Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ca43c00a45c1826","local-member-id":"873be3731fd7a3eb","added-peer-id":"873be3731fd7a3eb","added-peer-peer-urls":["https://162.253.55.144:2380"]} {"level":"info","ts":"2026-09-01T18:22:17.802041Z","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-01T18:22:17.802217Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.144:2380"} {"level":"info","ts":"2026-09-01T18:22:17.802272Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.144:2380"} {"level":"info","ts":"2026-09-01T18:22:17.802483Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"873be3731fd7a3eb","initial-advertise-peer-urls":["https://162.253.55.144:2380"],"listen-peer-urls":["https://162.253.55.144:2380"],"advertise-client-urls":["https://162.253.55.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T18:22:17.802516Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T18:22:18.184937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T18:22:18.185009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T18:22:18.185029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb received MsgPreVoteResp from 873be3731fd7a3eb at term 1"} {"level":"info","ts":"2026-09-01T18:22:18.185043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became candidate at term 2"} {"level":"info","ts":"2026-09-01T18:22:18.185051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb received MsgVoteResp from 873be3731fd7a3eb at term 2"} {"level":"info","ts":"2026-09-01T18:22:18.185061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became leader at term 2"} {"level":"info","ts":"2026-09-01T18:22:18.185071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 873be3731fd7a3eb elected leader 873be3731fd7a3eb at term 2"} {"level":"info","ts":"2026-09-01T18:22:18.18589Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"873be3731fd7a3eb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.144:2379]}","request-path":"/0/members/873be3731fd7a3eb/attributes","cluster-id":"9ca43c00a45c1826","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T18:22:18.186059Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:22:18.186172Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:22:18.186897Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ca43c00a45c1826","local-member-id":"873be3731fd7a3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:22:18.186981Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:22:18.187014Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:22:18.188625Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T18:22:18.205907Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:22:18.205924Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T18:22:18.206386Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T18:22:18.208825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.144:2379"} {"level":"info","ts":"2026-09-01T18:22:20.741898Z","caller":"traceutil/trace.go:171","msg":"trace[865257621] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"121.262547ms","start":"2026-09-01T18:22:20.620611Z","end":"2026-09-01T18:22:20.741874Z","steps":["trace[865257621] 'read index received' (duration: 121.071755ms)","trace[865257621] 'applied index is now lower than readState.Index' (duration: 189.692µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:22:20.741919Z","caller":"traceutil/trace.go:171","msg":"trace[516529445] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"122.473218ms","start":"2026-09-01T18:22:20.619426Z","end":"2026-09-01T18:22:20.741899Z","steps":["trace[516529445] 'process raft request' (duration: 122.293007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:22:20.742123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.513929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-01T18:22:20.74224Z","caller":"traceutil/trace.go:171","msg":"trace[712078367] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:72; }","duration":"121.63222ms","start":"2026-09-01T18:22:20.620551Z","end":"2026-09-01T18:22:20.742183Z","steps":["trace[712078367] 'agreement among raft nodes before linearized reading' (duration: 121.469448ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:22:21.146149Z","caller":"traceutil/trace.go:171","msg":"trace[1615363950] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"398.35257ms","start":"2026-09-01T18:22:20.747771Z","end":"2026-09-01T18:22:21.146124Z","steps":["trace[1615363950] 'read index received' (duration: 398.283799ms)","trace[1615363950] 'applied index is now lower than readState.Index' (duration: 67.65µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:22:21.146251Z","caller":"traceutil/trace.go:171","msg":"trace[1837896426] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"399.48796ms","start":"2026-09-01T18:22:20.746679Z","end":"2026-09-01T18:22:21.146167Z","steps":["trace[1837896426] 'process raft request' (duration: 399.302548ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:22:21.146371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.572301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-01T18:22:21.146417Z","caller":"traceutil/trace.go:171","msg":"trace[1886292952] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:73; }","duration":"398.656272ms","start":"2026-09-01T18:22:20.747749Z","end":"2026-09-01T18:22:21.146406Z","steps":["trace[1886292952] 'agreement among raft nodes before linearized reading' (duration: 398.502511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:22:21.14645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:22:20.747738Z","time spent":"398.704012ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-09-01T18:22:21.147581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:22:20.74666Z","time spent":"399.754442ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-01T18:22:21.168663Z","caller":"traceutil/trace.go:171","msg":"trace[1957347938] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"139.96151ms","start":"2026-09-01T18:22:21.028622Z","end":"2026-09-01T18:22:21.168583Z","steps":["trace[1957347938] 'process raft request' (duration: 139.780208ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:22:54.218861Z","caller":"traceutil/trace.go:171","msg":"trace[2051762080] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"143.247686ms","start":"2026-09-01T18:22:54.075591Z","end":"2026-09-01T18:22:54.218839Z","steps":["trace[2051762080] 'read index received' (duration: 143.019938ms)","trace[2051762080] 'applied index is now lower than readState.Index' (duration: 226.458µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:22:54.218924Z","caller":"traceutil/trace.go:171","msg":"trace[718598600] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"160.311093ms","start":"2026-09-01T18:22:54.058593Z","end":"2026-09-01T18:22:54.218904Z","steps":["trace[718598600] 'process raft request' (duration: 160.101767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:22:54.219059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.451742ms","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-01T18:22:54.219104Z","caller":"traceutil/trace.go:171","msg":"trace[1421297617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:480; }","duration":"143.532649ms","start":"2026-09-01T18:22:54.075556Z","end":"2026-09-01T18:22:54.219089Z","steps":["trace[1421297617] 'agreement among raft nodes before linearized reading' (duration: 143.442042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:22:54.219135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.227839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:22:54.219225Z","caller":"traceutil/trace.go:171","msg":"trace[1112606806] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:480; }","duration":"112.369979ms","start":"2026-09-01T18:22:54.106833Z","end":"2026-09-01T18:22:54.219203Z","steps":["trace[1112606806] 'agreement among raft nodes before linearized reading' (duration: 112.210247ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:23:00.581915Z","caller":"traceutil/trace.go:171","msg":"trace[1451266596] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"197.945099ms","start":"2026-09-01T18:23:00.383946Z","end":"2026-09-01T18:23:00.581891Z","steps":["trace[1451266596] 'read index received' (duration: 105.454092ms)","trace[1451266596] 'applied index is now lower than readState.Index' (duration: 92.490007ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:23:00.582031Z","caller":"traceutil/trace.go:171","msg":"trace[569994919] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"293.917726ms","start":"2026-09-01T18:23:00.288077Z","end":"2026-09-01T18:23:00.581995Z","steps":["trace[569994919] 'process raft request' (duration: 201.391066ms)","trace[569994919] 'compare' (duration: 92.094878ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:23:00.582157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.194847ms","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-01T18:23:00.582211Z","caller":"traceutil/trace.go:171","msg":"trace[963908027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"198.296725ms","start":"2026-09-01T18:23:00.383903Z","end":"2026-09-01T18:23:00.5822Z","steps":["trace[963908027] 'agreement among raft nodes before linearized reading' (duration: 198.09079ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:23:01.042602Z","caller":"traceutil/trace.go:171","msg":"trace[1271036641] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"544.733639ms","start":"2026-09-01T18:23:00.497844Z","end":"2026-09-01T18:23:01.042577Z","steps":["trace[1271036641] 'process raft request' (duration: 544.211011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:23:01.043001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:23:00.497811Z","time spent":"544.854769ms","remote":"127.0.0.1:54710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:23:01.071167Z","caller":"traceutil/trace.go:171","msg":"trace[1353466947] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"292.218506ms","start":"2026-09-01T18:23:00.778925Z","end":"2026-09-01T18:23:01.071143Z","steps":["trace[1353466947] 'read index received' (duration: 262.953917ms)","trace[1353466947] 'applied index is now lower than readState.Index' (duration: 29.261749ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:23:01.071372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.427021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5msj6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-01T18:23:01.071442Z","caller":"traceutil/trace.go:171","msg":"trace[1065642190] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5msj6; range_end:; response_count:1; response_revision:590; }","duration":"292.54593ms","start":"2026-09-01T18:23:00.778884Z","end":"2026-09-01T18:23:01.07143Z","steps":["trace[1065642190] 'agreement among raft nodes before linearized reading' (duration: 292.367407ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:23:01.071717Z","caller":"traceutil/trace.go:171","msg":"trace[297716200] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"485.956902ms","start":"2026-09-01T18:23:00.585749Z","end":"2026-09-01T18:23:01.071705Z","steps":["trace[297716200] 'process raft request' (duration: 485.286623ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:23:01.071801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:23:00.585728Z","time spent":"486.015466ms","remote":"127.0.0.1:54874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:23:01.780415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710774188620855,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-01T18:23:01.989466Z","caller":"traceutil/trace.go:171","msg":"trace[1348349530] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"851.746335ms","start":"2026-09-01T18:23:01.137695Z","end":"2026-09-01T18:23:01.989441Z","steps":["trace[1348349530] 'process raft request' (duration: 851.632256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:23:01.989591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:23:01.137674Z","time spent":"851.859744ms","remote":"127.0.0.1:55186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T18:23:01.989757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.897727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:23:01.989862Z","caller":"traceutil/trace.go:171","msg":"trace[1927452532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"710.057269ms","start":"2026-09-01T18:23:01.279786Z","end":"2026-09-01T18:23:01.989843Z","steps":["trace[1927452532] 'agreement among raft nodes before linearized reading' (duration: 709.840853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:23:01.989905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:23:01.279764Z","time spent":"710.132404ms","remote":"127.0.0.1:54614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T18:23:01.99067Z","caller":"traceutil/trace.go:171","msg":"trace[105401889] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:617; }","duration":"709.617697ms","start":"2026-09-01T18:23:01.279835Z","end":"2026-09-01T18:23:01.989453Z","steps":["trace[105401889] 'read index received' (duration: 709.608186ms)","trace[105401889] 'applied index is now lower than readState.Index' (duration: 7.5µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:23:01.990887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.794757ms","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-01T18:23:01.990944Z","caller":"traceutil/trace.go:171","msg":"trace[1080584698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"592.881282ms","start":"2026-09-01T18:23:01.398041Z","end":"2026-09-01T18:23:01.990922Z","steps":["trace[1080584698] 'agreement among raft nodes before linearized reading' (duration: 592.757673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:23:01.990993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:23:01.398018Z","time spent":"592.964089ms","remote":"127.0.0.1:54874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-01T18:23:04.010482Z","caller":"traceutil/trace.go:171","msg":"trace[306510518] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"114.518277ms","start":"2026-09-01T18:23:03.895941Z","end":"2026-09-01T18:23:04.01046Z","steps":["trace[306510518] 'process raft request' (duration: 75.018935ms)","trace[306510518] 'compare' (duration: 39.368673ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:23:19.006897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.446455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-f8wkp\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-01T18:23:19.006971Z","caller":"traceutil/trace.go:171","msg":"trace[1118556491] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-f8wkp; range_end:; response_count:1; response_revision:760; }","duration":"126.585063ms","start":"2026-09-01T18:23:18.880368Z","end":"2026-09-01T18:23:19.006954Z","steps":["trace[1118556491] 'range keys from in-memory index tree' (duration: 126.299827ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:23:19.121618Z","caller":"traceutil/trace.go:171","msg":"trace[868672637] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"109.50163ms","start":"2026-09-01T18:23:19.012091Z","end":"2026-09-01T18:23:19.121592Z","steps":["trace[868672637] 'process raft request' (duration: 109.367852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:23:55.195026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.614314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/cert-manager/self-signed-ca\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:23:55.195111Z","caller":"traceutil/trace.go:171","msg":"trace[987555416] range","detail":"{range_begin:/registry/cert-manager.io/certificates/cert-manager/self-signed-ca; range_end:; response_count:0; response_revision:1205; }","duration":"118.746119ms","start":"2026-09-01T18:23:55.076344Z","end":"2026-09-01T18:23:55.19509Z","steps":["trace[987555416] 'range keys from in-memory index tree' (duration: 118.5201ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:24:14.36567Z","caller":"traceutil/trace.go:171","msg":"trace[1592612549] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"149.151643ms","start":"2026-09-01T18:24:14.216482Z","end":"2026-09-01T18:24:14.365634Z","steps":["trace[1592612549] 'process raft request' (duration: 136.607637ms)","trace[1592612549] 'compare' (duration: 12.282687ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:24:39.746148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.048537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:24:39.746253Z","caller":"traceutil/trace.go:171","msg":"trace[330549707] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1841; }","duration":"146.197471ms","start":"2026-09-01T18:24:39.60003Z","end":"2026-09-01T18:24:39.746228Z","steps":["trace[330549707] 'count revisions from in-memory index tree' (duration: 145.940585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:39.746583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.549986ms","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-01T18:24:39.746689Z","caller":"traceutil/trace.go:171","msg":"trace[55921106] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1841; }","duration":"109.70654ms","start":"2026-09-01T18:24:39.636957Z","end":"2026-09-01T18:24:39.746663Z","steps":["trace[55921106] 'range keys from in-memory index tree' (duration: 109.233127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:40.39293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.662846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4e8a6ec5-9a39-42b0-b0ca-bf90d9e18f9f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-01T18:24:40.393022Z","caller":"traceutil/trace.go:171","msg":"trace[1928834589] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4e8a6ec5-9a39-42b0-b0ca-bf90d9e18f9f; range_end:; response_count:1; response_revision:1843; }","duration":"318.767149ms","start":"2026-09-01T18:24:40.074232Z","end":"2026-09-01T18:24:40.392999Z","steps":["trace[1928834589] 'range keys from in-memory index tree' (duration: 318.463141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:40.393028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.281299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4e8a6ec5-9a39-42b0-b0ca-bf90d9e18f9f\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-09-01T18:24:40.393025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.450365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-09-01T18:24:40.393066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:24:40.074212Z","time spent":"318.843452ms","remote":"127.0.0.1:54800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4e8a6ec5-9a39-42b0-b0ca-bf90d9e18f9f\" "} {"level":"info","ts":"2026-09-01T18:24:40.393092Z","caller":"traceutil/trace.go:171","msg":"trace[299714454] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4e8a6ec5-9a39-42b0-b0ca-bf90d9e18f9f; range_end:; response_count:1; response_revision:1843; }","duration":"122.413052ms","start":"2026-09-01T18:24:40.27066Z","end":"2026-09-01T18:24:40.393073Z","steps":["trace[299714454] 'range keys from in-memory index tree' (duration: 122.088263ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:24:40.393092Z","caller":"traceutil/trace.go:171","msg":"trace[147250383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1843; }","duration":"139.553989ms","start":"2026-09-01T18:24:40.253525Z","end":"2026-09-01T18:24:40.393079Z","steps":["trace[147250383] 'range keys from in-memory index tree' (duration: 139.301642ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:24:40.745554Z","caller":"traceutil/trace.go:171","msg":"trace[1845941006] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"344.836854ms","start":"2026-09-01T18:24:40.400669Z","end":"2026-09-01T18:24:40.745506Z","steps":["trace[1845941006] 'process raft request' (duration: 344.637339ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:24:40.745703Z","caller":"traceutil/trace.go:171","msg":"trace[1299409487] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"296.85654ms","start":"2026-09-01T18:24:40.448824Z","end":"2026-09-01T18:24:40.745681Z","steps":["trace[1299409487] 'process raft request' (duration: 296.606153ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:24:40.745772Z","caller":"traceutil/trace.go:171","msg":"trace[859687932] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1918; }","duration":"267.064862ms","start":"2026-09-01T18:24:40.478687Z","end":"2026-09-01T18:24:40.745752Z","steps":["trace[859687932] 'read index received' (duration: 14.03341ms)","trace[859687932] 'applied index is now lower than readState.Index' (duration: 253.030102ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:24:40.745834Z","caller":"traceutil/trace.go:171","msg":"trace[1614361064] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"286.508653ms","start":"2026-09-01T18:24:40.459299Z","end":"2026-09-01T18:24:40.745808Z","steps":["trace[1614361064] 'process raft request' (duration: 286.285617ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:24:40.745921Z","caller":"traceutil/trace.go:171","msg":"trace[13727083] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"266.480086ms","start":"2026-09-01T18:24:40.479423Z","end":"2026-09-01T18:24:40.745904Z","steps":["trace[13727083] 'process raft request' (duration: 266.25367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:40.746079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.384372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:24:40.746149Z","caller":"traceutil/trace.go:171","msg":"trace[576701166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1848; }","duration":"267.505285ms","start":"2026-09-01T18:24:40.478632Z","end":"2026-09-01T18:24:40.746138Z","steps":["trace[576701166] 'agreement among raft nodes before linearized reading' (duration: 267.359921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:40.746263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.303116ms","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-01T18:24:40.746388Z","caller":"traceutil/trace.go:171","msg":"trace[896343611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1848; }","duration":"237.46957ms","start":"2026-09-01T18:24:40.508897Z","end":"2026-09-01T18:24:40.746366Z","steps":["trace[896343611] 'agreement among raft nodes before linearized reading' (duration: 237.041708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:40.746567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:24:40.400656Z","time spent":"345.098371ms","remote":"127.0.0.1:54800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:24:40.861875Z","caller":"traceutil/trace.go:171","msg":"trace[1571937867] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"109.864544ms","start":"2026-09-01T18:24:40.751979Z","end":"2026-09-01T18:24:40.861844Z","steps":["trace[1571937867] 'process raft request' (duration: 52.412237ms)","trace[1571937867] 'compare' (duration: 56.892961ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:24:40.862192Z","caller":"traceutil/trace.go:171","msg":"trace[704850682] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"109.737229ms","start":"2026-09-01T18:24:40.752423Z","end":"2026-09-01T18:24:40.86216Z","steps":["trace[704850682] 'process raft request' (duration: 109.343798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:25:15.323266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.254734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:25:15.323407Z","caller":"traceutil/trace.go:171","msg":"trace[1726916200] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"294.61524ms","start":"2026-09-01T18:25:15.028766Z","end":"2026-09-01T18:25:15.323381Z","steps":["trace[1726916200] 'process raft request' (duration: 127.939766ms)","trace[1726916200] 'compare' (duration: 166.06868ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:25:15.345298Z","caller":"traceutil/trace.go:171","msg":"trace[1498063991] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"316.466695ms","start":"2026-09-01T18:25:15.028816Z","end":"2026-09-01T18:25:15.345282Z","steps":["trace[1498063991] 'process raft request' (duration: 316.287521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:25:15.345395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:25:15.02879Z","time spent":"316.552837ms","remote":"127.0.0.1:54874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:25:15.345415Z","caller":"traceutil/trace.go:171","msg":"trace[243340610] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2168; }","duration":"208.48803ms","start":"2026-09-01T18:25:15.136706Z","end":"2026-09-01T18:25:15.345194Z","steps":["trace[243340610] 'read index received' (duration: 20.007112ms)","trace[243340610] 'applied index is now lower than readState.Index' (duration: 188.479868ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:25:15.345664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.92375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:25:15.345762Z","caller":"traceutil/trace.go:171","msg":"trace[287575469] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2088; }","duration":"209.061133ms","start":"2026-09-01T18:25:15.136678Z","end":"2026-09-01T18:25:15.345739Z","steps":["trace[287575469] 'agreement among raft nodes before linearized reading' (duration: 208.728236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:25:15.345684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.00307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:25:15.345942Z","caller":"traceutil/trace.go:171","msg":"trace[1019866527] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2088; }","duration":"201.256487ms","start":"2026-09-01T18:25:15.144633Z","end":"2026-09-01T18:25:15.345889Z","steps":["trace[1019866527] 'agreement among raft nodes before linearized reading' (duration: 200.999051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:20.037897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.738318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:26:20.038004Z","caller":"traceutil/trace.go:171","msg":"trace[1302092825] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2539; }","duration":"113.895241ms","start":"2026-09-01T18:26:19.92409Z","end":"2026-09-01T18:26:20.037985Z","steps":["trace[1302092825] 'count revisions from in-memory index tree' (duration: 113.646376ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:20.038246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.111776ms","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-01T18:26:20.038351Z","caller":"traceutil/trace.go:171","msg":"trace[1013741187] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2539; }","duration":"119.271348ms","start":"2026-09-01T18:26:19.91905Z","end":"2026-09-01T18:26:20.038321Z","steps":["trace[1013741187] 'range keys from in-memory index tree' (duration: 118.80952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:51.804786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.429561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:26:51.804887Z","caller":"traceutil/trace.go:171","msg":"trace[1755809027] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2712; }","duration":"254.576964ms","start":"2026-09-01T18:26:51.55029Z","end":"2026-09-01T18:26:51.804867Z","steps":["trace[1755809027] 'count revisions from in-memory index tree' (duration: 254.34493ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:52.084089Z","caller":"traceutil/trace.go:171","msg":"trace[1284998392] linearizableReadLoop","detail":"{readStateIndex:2819; appliedIndex:2818; }","duration":"146.84964ms","start":"2026-09-01T18:26:51.937214Z","end":"2026-09-01T18:26:52.084063Z","steps":["trace[1284998392] 'read index received' (duration: 128.610318ms)","trace[1284998392] 'applied index is now lower than readState.Index' (duration: 18.237992ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:26:52.084293Z","caller":"traceutil/trace.go:171","msg":"trace[1232726924] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"199.925282ms","start":"2026-09-01T18:26:51.884215Z","end":"2026-09-01T18:26:52.08414Z","steps":["trace[1232726924] 'process raft request' (duration: 181.704519ms)","trace[1232726924] 'compare' (duration: 17.985289ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:26:52.084408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.137085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-01T18:26:52.084588Z","caller":"traceutil/trace.go:171","msg":"trace[663535006] range","detail":"{range_begin:/registry/masterleases/162.253.55.144; range_end:; response_count:1; response_revision:2715; }","duration":"147.39907ms","start":"2026-09-01T18:26:51.937171Z","end":"2026-09-01T18:26:52.08457Z","steps":["trace[663535006] 'agreement among raft nodes before linearized reading' (duration: 146.975783ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:57.359214Z","caller":"traceutil/trace.go:171","msg":"trace[892063648] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"180.420654ms","start":"2026-09-01T18:26:57.178766Z","end":"2026-09-01T18:26:57.359187Z","steps":["trace[892063648] 'process raft request' (duration: 180.23739ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:57.524422Z","caller":"traceutil/trace.go:171","msg":"trace[1983108545] linearizableReadLoop","detail":"{readStateIndex:2850; appliedIndex:2849; }","duration":"136.611568ms","start":"2026-09-01T18:26:57.387773Z","end":"2026-09-01T18:26:57.524385Z","steps":["trace[1983108545] 'read index received' (duration: 123.803789ms)","trace[1983108545] 'applied index is now lower than readState.Index' (duration: 12.806689ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:26:57.524519Z","caller":"traceutil/trace.go:171","msg":"trace[1079847452] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"158.065613ms","start":"2026-09-01T18:26:57.366409Z","end":"2026-09-01T18:26:57.524475Z","steps":["trace[1079847452] 'process raft request' (duration: 145.319566ms)","trace[1079847452] 'compare' (duration: 12.144607ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:26:57.524742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.909883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18d145ba0a993c95\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-09-01T18:26:57.524835Z","caller":"traceutil/trace.go:171","msg":"trace[1931428504] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18d145ba0a993c95; range_end:; response_count:1; response_revision:2744; }","duration":"137.091366ms","start":"2026-09-01T18:26:57.387729Z","end":"2026-09-01T18:26:57.52482Z","steps":["trace[1931428504] 'agreement among raft nodes before linearized reading' (duration: 136.75532ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:21.545772Z","caller":"traceutil/trace.go:171","msg":"trace[782054596] linearizableReadLoop","detail":"{readStateIndex:5004; appliedIndex:5002; }","duration":"224.80361ms","start":"2026-09-01T18:31:21.320945Z","end":"2026-09-01T18:31:21.545749Z","steps":["trace[782054596] 'read index received' (duration: 965.401µs)","trace[782054596] 'applied index is now lower than readState.Index' (duration: 223.836949ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:31:21.545914Z","caller":"traceutil/trace.go:171","msg":"trace[590355374] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4817; }","duration":"230.355405ms","start":"2026-09-01T18:31:21.315501Z","end":"2026-09-01T18:31:21.545856Z","steps":["trace[590355374] 'process raft request' (duration: 230.133861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:21.54617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.149407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-01T18:31:21.546235Z","caller":"traceutil/trace.go:171","msg":"trace[1645180579] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4817; }","duration":"225.29675ms","start":"2026-09-01T18:31:21.320926Z","end":"2026-09-01T18:31:21.546222Z","steps":["trace[1645180579] 'agreement among raft nodes before linearized reading' (duration: 225.040305ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:21.671621Z","caller":"traceutil/trace.go:171","msg":"trace[1012125502] linearizableReadLoop","detail":"{readStateIndex:5007; appliedIndex:5004; }","duration":"125.649226ms","start":"2026-09-01T18:31:21.545944Z","end":"2026-09-01T18:31:21.671593Z","steps":["trace[1012125502] 'read index received' (duration: 124.709356ms)","trace[1012125502] 'applied index is now lower than readState.Index' (duration: 938.68µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:31:21.671746Z","caller":"traceutil/trace.go:171","msg":"trace[1684258935] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"320.127825ms","start":"2026-09-01T18:31:21.351592Z","end":"2026-09-01T18:31:21.67172Z","steps":["trace[1684258935] 'process raft request' (duration: 319.859299ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:21.671674Z","caller":"traceutil/trace.go:171","msg":"trace[1962534651] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"236.724758ms","start":"2026-09-01T18:31:21.434912Z","end":"2026-09-01T18:31:21.671637Z","steps":["trace[1962534651] 'process raft request' (duration: 236.622386ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:21.671977Z","caller":"traceutil/trace.go:171","msg":"trace[496029323] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"346.141855ms","start":"2026-09-01T18:31:21.325818Z","end":"2026-09-01T18:31:21.67196Z","steps":["trace[496029323] 'process raft request' (duration: 344.780167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:21.672745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.061698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-01T18:31:21.672782Z","caller":"traceutil/trace.go:171","msg":"trace[1540474409] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4820; }","duration":"191.125378ms","start":"2026-09-01T18:31:21.481648Z","end":"2026-09-01T18:31:21.672773Z","steps":["trace[1540474409] 'agreement among raft nodes before linearized reading' (duration: 190.301751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:21.672721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:31:21.325808Z","time spent":"346.81278ms","remote":"127.0.0.1:54692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":861,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T18:31:21.672102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.79497ms","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-01T18:31:21.673171Z","caller":"traceutil/trace.go:171","msg":"trace[1896312199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4820; }","duration":"347.920793ms","start":"2026-09-01T18:31:21.325233Z","end":"2026-09-01T18:31:21.673154Z","steps":["trace[1896312199] 'agreement among raft nodes before linearized reading' (duration: 346.725888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:21.673254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:31:21.325222Z","time spent":"347.988624ms","remote":"127.0.0.1:54874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-01T18:31:21.673375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:31:21.351571Z","time spent":"320.737997ms","remote":"127.0.0.1:54874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:31:21.672207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.007084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-pr8p6-tls-8qs2f\" ","response":"range_response_count:1 size:2625"} {"level":"warn","ts":"2026-09-01T18:31:21.67221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.780764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-01T18:31:21.674073Z","caller":"traceutil/trace.go:171","msg":"trace[1444125547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4820; }","duration":"244.686063ms","start":"2026-09-01T18:31:21.429375Z","end":"2026-09-01T18:31:21.674061Z","steps":["trace[1444125547] 'agreement among raft nodes before linearized reading' (duration: 242.359515ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:21.674325Z","caller":"traceutil/trace.go:171","msg":"trace[1385759273] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-pr8p6-tls-8qs2f; range_end:; response_count:1; response_revision:4820; }","duration":"349.143478ms","start":"2026-09-01T18:31:21.325151Z","end":"2026-09-01T18:31:21.674295Z","steps":["trace[1385759273] 'agreement among raft nodes before linearized reading' (duration: 346.903521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:21.674419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:31:21.325142Z","time spent":"349.24804ms","remote":"127.0.0.1:54710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":2649,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-pr8p6-tls-8qs2f\" "} {"level":"warn","ts":"2026-09-01T18:31:21.690962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.329772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-01T18:31:21.691051Z","caller":"traceutil/trace.go:171","msg":"trace[1189946647] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4821; }","duration":"140.477355ms","start":"2026-09-01T18:31:21.550553Z","end":"2026-09-01T18:31:21.69103Z","steps":["trace[1189946647] 'agreement among raft nodes before linearized reading' (duration: 140.226449ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:21.691483Z","caller":"traceutil/trace.go:171","msg":"trace[1619007999] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"125.465212ms","start":"2026-09-01T18:31:21.565999Z","end":"2026-09-01T18:31:21.691464Z","steps":["trace[1619007999] 'process raft request' (duration: 124.621664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:21.691995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.998928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-01T18:31:21.692043Z","caller":"traceutil/trace.go:171","msg":"trace[1142517864] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4821; }","duration":"107.090279ms","start":"2026-09-01T18:31:21.58494Z","end":"2026-09-01T18:31:21.69203Z","steps":["trace[1142517864] 'agreement among raft nodes before linearized reading' (duration: 106.975757ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:21.802839Z","caller":"traceutil/trace.go:171","msg":"trace[1089425163] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"126.170457ms","start":"2026-09-01T18:31:21.676649Z","end":"2026-09-01T18:31:21.802819Z","steps":["trace[1089425163] 'process raft request' (duration: 98.073252ms)","trace[1089425163] 'compare' (duration: 27.744477ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:31:21.803101Z","caller":"traceutil/trace.go:171","msg":"trace[1254569758] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4823; }","duration":"126.33546ms","start":"2026-09-01T18:31:21.676732Z","end":"2026-09-01T18:31:21.803067Z","steps":["trace[1254569758] 'process raft request' (duration: 126.002713ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:21.803168Z","caller":"traceutil/trace.go:171","msg":"trace[1247142455] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"105.483786ms","start":"2026-09-01T18:31:21.697677Z","end":"2026-09-01T18:31:21.803161Z","steps":["trace[1247142455] 'process raft request' (duration: 105.435085ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:21.803276Z","caller":"traceutil/trace.go:171","msg":"trace[395687197] linearizableReadLoop","detail":"{readStateIndex:5011; appliedIndex:5008; }","duration":"105.583388ms","start":"2026-09-01T18:31:21.697669Z","end":"2026-09-01T18:31:21.803252Z","steps":["trace[395687197] 'read index received' (duration: 77.078774ms)","trace[395687197] 'applied index is now lower than readState.Index' (duration: 28.503594ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:31:21.803434Z","caller":"traceutil/trace.go:171","msg":"trace[1946349674] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"126.074904ms","start":"2026-09-01T18:31:21.677349Z","end":"2026-09-01T18:31:21.803423Z","steps":["trace[1946349674] 'process raft request' (duration: 125.611655ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:21.803524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.835823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"warn","ts":"2026-09-01T18:31:21.803555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.863433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-01T18:31:21.803616Z","caller":"traceutil/trace.go:171","msg":"trace[166293385] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4825; }","duration":"105.962176ms","start":"2026-09-01T18:31:21.697638Z","end":"2026-09-01T18:31:21.8036Z","steps":["trace[166293385] 'agreement among raft nodes before linearized reading' (duration: 105.757872ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:21.803568Z","caller":"traceutil/trace.go:171","msg":"trace[1665907679] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4825; }","duration":"105.921645ms","start":"2026-09-01T18:31:21.697632Z","end":"2026-09-01T18:31:21.803554Z","steps":["trace[1665907679] 'agreement among raft nodes before linearized reading' (duration: 105.768422ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:21.947252Z","caller":"traceutil/trace.go:171","msg":"trace[899720025] linearizableReadLoop","detail":"{readStateIndex:5014; appliedIndex:5013; }","duration":"107.189052ms","start":"2026-09-01T18:31:21.840034Z","end":"2026-09-01T18:31:21.947223Z","steps":["trace[899720025] 'read index received' (duration: 106.975158ms)","trace[899720025] 'applied index is now lower than readState.Index' (duration: 212.494µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:31:21.947598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.318602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-01T18:31:21.947661Z","caller":"traceutil/trace.go:171","msg":"trace[2081487063] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4827; }","duration":"104.403124ms","start":"2026-09-01T18:31:21.84324Z","end":"2026-09-01T18:31:21.947644Z","steps":["trace[2081487063] 'agreement among raft nodes before linearized reading' (duration: 104.164189ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:21.947961Z","caller":"traceutil/trace.go:171","msg":"trace[523570247] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"117.537577ms","start":"2026-09-01T18:31:21.830323Z","end":"2026-09-01T18:31:21.947861Z","steps":["trace[523570247] 'process raft request' (duration: 116.72271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:21.94804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.125263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-01T18:31:21.948091Z","caller":"traceutil/trace.go:171","msg":"trace[1371087826] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4827; }","duration":"110.749476ms","start":"2026-09-01T18:31:21.83733Z","end":"2026-09-01T18:31:21.94808Z","steps":["trace[1371087826] 'agreement among raft nodes before linearized reading' (duration: 110.064022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:26.080903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.314623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-01T18:31:26.080979Z","caller":"traceutil/trace.go:171","msg":"trace[571786749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4905; }","duration":"229.440315ms","start":"2026-09-01T18:31:25.851522Z","end":"2026-09-01T18:31:26.080962Z","steps":["trace[571786749] 'range keys from in-memory index tree' (duration: 229.15006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:26.081293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.255646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-01T18:31:26.081329Z","caller":"traceutil/trace.go:171","msg":"trace[812685598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4905; }","duration":"262.318178ms","start":"2026-09-01T18:31:25.819001Z","end":"2026-09-01T18:31:26.081319Z","steps":["trace[812685598] 'range keys from in-memory index tree' (duration: 262.163995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:26.081387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.519492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-01T18:31:26.081464Z","caller":"traceutil/trace.go:171","msg":"trace[856119964] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4905; }","duration":"262.644594ms","start":"2026-09-01T18:31:25.818801Z","end":"2026-09-01T18:31:26.081445Z","steps":["trace[856119964] 'range keys from in-memory index tree' (duration: 262.379199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:26.081508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.232799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-01T18:31:26.081585Z","caller":"traceutil/trace.go:171","msg":"trace[1134060367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4905; }","duration":"266.359542ms","start":"2026-09-01T18:31:25.815207Z","end":"2026-09-01T18:31:26.081567Z","steps":["trace[1134060367] 'range keys from in-memory index tree' (duration: 266.060205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:26.081628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.46485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:31:26.081769Z","caller":"traceutil/trace.go:171","msg":"trace[1852648120] linearizableReadLoop","detail":"{readStateIndex:5095; appliedIndex:5094; }","duration":"190.712883ms","start":"2026-09-01T18:31:25.891036Z","end":"2026-09-01T18:31:26.081749Z","steps":["trace[1852648120] 'read index received' (duration: 50.997057ms)","trace[1852648120] 'applied index is now lower than readState.Index' (duration: 139.714336ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:31:26.081874Z","caller":"traceutil/trace.go:171","msg":"trace[1468008621] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"221.418619ms","start":"2026-09-01T18:31:25.860443Z","end":"2026-09-01T18:31:26.081861Z","steps":["trace[1468008621] 'process raft request' (duration: 81.642172ms)","trace[1468008621] 'compare' (duration: 139.221835ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:31:26.081967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.072717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-pr8p6-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-09-01T18:31:26.082001Z","caller":"traceutil/trace.go:171","msg":"trace[294259801] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-pr8p6-tls; range_end:; response_count:1; response_revision:4906; }","duration":"146.148699ms","start":"2026-09-01T18:31:25.935842Z","end":"2026-09-01T18:31:26.081991Z","steps":["trace[294259801] 'agreement among raft nodes before linearized reading' (duration: 146.051327ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:26.082104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.068901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc865abc-d7e3-4981-a9a9-c91e5b44c563\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-01T18:31:26.082139Z","caller":"traceutil/trace.go:171","msg":"trace[637093031] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc865abc-d7e3-4981-a9a9-c91e5b44c563; range_end:; response_count:1; response_revision:4906; }","duration":"191.126431ms","start":"2026-09-01T18:31:25.891001Z","end":"2026-09-01T18:31:26.082127Z","steps":["trace[637093031] 'agreement among raft nodes before linearized reading' (duration: 191.030729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:28.42612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.483928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-01T18:31:28.426212Z","caller":"traceutil/trace.go:171","msg":"trace[1583585586] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4930; }","duration":"146.639721ms","start":"2026-09-01T18:31:28.27955Z","end":"2026-09-01T18:31:28.42619Z","steps":["trace[1583585586] 'range keys from in-memory index tree' (duration: 146.280124ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:37.12277Z","caller":"traceutil/trace.go:171","msg":"trace[1315825675] linearizableReadLoop","detail":"{readStateIndex:5261; appliedIndex:5260; }","duration":"117.763504ms","start":"2026-09-01T18:31:37.004983Z","end":"2026-09-01T18:31:37.122746Z","steps":["trace[1315825675] 'read index received' (duration: 114.896505ms)","trace[1315825675] 'applied index is now lower than readState.Index' (duration: 2.865119ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:31:37.122911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.902547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:31:37.122997Z","caller":"traceutil/trace.go:171","msg":"trace[1813040870] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:5066; }","duration":"118.005439ms","start":"2026-09-01T18:31:37.004969Z","end":"2026-09-01T18:31:37.122975Z","steps":["trace[1813040870] 'agreement among raft nodes before linearized reading' (duration: 117.832526ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:41.584951Z","caller":"traceutil/trace.go:171","msg":"trace[454138277] linearizableReadLoop","detail":"{readStateIndex:5300; appliedIndex:5299; }","duration":"366.17605ms","start":"2026-09-01T18:31:41.21875Z","end":"2026-09-01T18:31:41.584926Z","steps":["trace[454138277] 'read index received' (duration: 357.979462ms)","trace[454138277] 'applied index is now lower than readState.Index' (duration: 8.195588ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:31:41.585115Z","caller":"traceutil/trace.go:171","msg":"trace[1444312076] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"414.12284ms","start":"2026-09-01T18:31:41.170931Z","end":"2026-09-01T18:31:41.585054Z","steps":["trace[1444312076] 'process raft request' (duration: 405.881591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:41.585315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:31:41.170904Z","time spent":"414.295394ms","remote":"127.0.0.1:54874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:31:41.585363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.863583ms","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-01T18:31:41.585447Z","caller":"traceutil/trace.go:171","msg":"trace[574645557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5105; }","duration":"248.977246ms","start":"2026-09-01T18:31:41.336453Z","end":"2026-09-01T18:31:41.58543Z","steps":["trace[574645557] 'agreement among raft nodes before linearized reading' (duration: 248.782452ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:41.585471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.17456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-01T18:31:41.585597Z","caller":"traceutil/trace.go:171","msg":"trace[288789702] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5105; }","duration":"365.364803ms","start":"2026-09-01T18:31:41.220207Z","end":"2026-09-01T18:31:41.585572Z","steps":["trace[288789702] 'agreement among raft nodes before linearized reading' (duration: 365.020886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:41.585659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:31:41.220199Z","time spent":"365.445295ms","remote":"127.0.0.1:54874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-09-01T18:31:41.585629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.885215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-09-01T18:31:41.585707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.946206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:31:41.585749Z","caller":"traceutil/trace.go:171","msg":"trace[783712016] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5105; }","duration":"367.032608ms","start":"2026-09-01T18:31:41.218706Z","end":"2026-09-01T18:31:41.585738Z","steps":["trace[783712016] 'agreement among raft nodes before linearized reading' (duration: 366.962936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:41.585779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:31:41.218682Z","time spent":"367.087048ms","remote":"127.0.0.1:37256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-09-01T18:31:41.58575Z","caller":"traceutil/trace.go:171","msg":"trace[1822018825] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5105; }","duration":"276.09109ms","start":"2026-09-01T18:31:41.309611Z","end":"2026-09-01T18:31:41.585702Z","steps":["trace[1822018825] 'agreement among raft nodes before linearized reading' (duration: 275.728392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:41.585916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.90362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:31:41.585955Z","caller":"traceutil/trace.go:171","msg":"trace[1870235352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5105; }","duration":"308.967292ms","start":"2026-09-01T18:31:41.276977Z","end":"2026-09-01T18:31:41.585944Z","steps":["trace[1870235352] 'agreement among raft nodes before linearized reading' (duration: 308.909431ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:41.585978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:31:41.276961Z","time spent":"309.011692ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T18:31:41.58602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.325469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-01T18:31:41.586052Z","caller":"traceutil/trace.go:171","msg":"trace[790126246] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5105; }","duration":"239.39189ms","start":"2026-09-01T18:31:41.34665Z","end":"2026-09-01T18:31:41.586042Z","steps":["trace[790126246] 'agreement among raft nodes before linearized reading' (duration: 239.298288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:41.587771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.284568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:31:41.587838Z","caller":"traceutil/trace.go:171","msg":"trace[1521813913] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5105; }","duration":"261.379379ms","start":"2026-09-01T18:31:41.326445Z","end":"2026-09-01T18:31:41.587825Z","steps":["trace[1521813913] 'agreement among raft nodes before linearized reading' (duration: 259.184074ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:41.707832Z","caller":"traceutil/trace.go:171","msg":"trace[1407403628] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"107.870664ms","start":"2026-09-01T18:31:41.599935Z","end":"2026-09-01T18:31:41.707806Z","steps":["trace[1407403628] 'process raft request' (duration: 107.713741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:42.095578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.918357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-01T18:31:42.095648Z","caller":"traceutil/trace.go:171","msg":"trace[1133707501] range","detail":"{range_begin:/registry/masterleases/162.253.55.144; range_end:; response_count:1; response_revision:5114; }","duration":"143.04004ms","start":"2026-09-01T18:31:41.95259Z","end":"2026-09-01T18:31:42.09563Z","steps":["trace[1133707501] 'range keys from in-memory index tree' (duration: 142.677373ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:32:18.221862Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2855} {"level":"info","ts":"2026-09-01T18:32:18.301699Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2855,"took":"78.58553ms","hash":1296052864,"current-db-size-bytes":52740096,"current-db-size":"53 MB","current-db-size-in-use-bytes":28438528,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T18:32:18.301767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1296052864,"revision":2855,"compact-revision":-1} {"level":"warn","ts":"2026-09-01T18:32:26.271206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.246002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-09-01T18:32:26.271616Z","caller":"traceutil/trace.go:171","msg":"trace[469762154] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5478; }","duration":"131.69472ms","start":"2026-09-01T18:32:26.139894Z","end":"2026-09-01T18:32:26.271588Z","steps":["trace[469762154] 'range keys from in-memory index tree' (duration: 130.980847ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:37:18.23183Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5433} {"level":"info","ts":"2026-09-01T18:37:18.319154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5433,"took":"84.839645ms","hash":1006758122,"current-db-size-bytes":52740096,"current-db-size":"53 MB","current-db-size-in-use-bytes":18481152,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-01T18:37:18.319224Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1006758122,"revision":5433,"compact-revision":2855}