{"level":"warn","ts":"2026-09-17T21:23:45.241525Z","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-17T21:23:45.241655Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.111: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.111:2380","--initial-cluster=instance=https://162.253.55.111:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.111:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.111: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-17T21:23:45.241752Z","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-17T21:23:45.2418Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.111:2380"]} {"level":"info","ts":"2026-09-17T21:23:45.241863Z","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-17T21:23:45.242418Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"]} {"level":"info","ts":"2026-09-17T21:23:45.242525Z","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.111:2380"],"listen-peer-urls":["https://162.253.55.111:2380"],"advertise-client-urls":["https://162.253.55.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.111: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-17T21:23:45.404458Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"102.074574ms"} {"level":"info","ts":"2026-09-17T21:23:45.432255Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"299bf3744060cbd","cluster-id":"83d94f04ddaf7996"} {"level":"info","ts":"2026-09-17T21:23:45.432425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=()"} {"level":"info","ts":"2026-09-17T21:23:45.432483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became follower at term 0"} {"level":"info","ts":"2026-09-17T21:23:45.432526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 299bf3744060cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-17T21:23:45.432627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became follower at term 1"} {"level":"info","ts":"2026-09-17T21:23:45.432711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=(187391103597939901)"} {"level":"warn","ts":"2026-09-17T21:23:45.449283Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-17T21:23:45.450873Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-17T21:23:45.45187Z","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-17T21:23:45.453865Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"299bf3744060cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-17T21:23:45.455019Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"299bf3744060cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-17T21:23:45.456627Z","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-17T21:23:45.457029Z","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-17T21:23:45.457071Z","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-17T21:23:45.466094Z","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-17T21:23:45.466208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=(187391103597939901)"} {"level":"info","ts":"2026-09-17T21:23:45.466271Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"299bf3744060cbd","initial-advertise-peer-urls":["https://162.253.55.111:2380"],"listen-peer-urls":["https://162.253.55.111:2380"],"advertise-client-urls":["https://162.253.55.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-17T21:23:45.466294Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-17T21:23:45.466383Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"83d94f04ddaf7996","local-member-id":"299bf3744060cbd","added-peer-id":"299bf3744060cbd","added-peer-peer-urls":["https://162.253.55.111:2380"]} {"level":"info","ts":"2026-09-17T21:23:45.466399Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.111:2380"} {"level":"info","ts":"2026-09-17T21:23:45.466455Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.111:2380"} {"level":"info","ts":"2026-09-17T21:23:46.233257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd is starting a new election at term 1"} {"level":"info","ts":"2026-09-17T21:23:46.233302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-09-17T21:23:46.233341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd received MsgPreVoteResp from 299bf3744060cbd at term 1"} {"level":"info","ts":"2026-09-17T21:23:46.233356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became candidate at term 2"} {"level":"info","ts":"2026-09-17T21:23:46.233363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd received MsgVoteResp from 299bf3744060cbd at term 2"} {"level":"info","ts":"2026-09-17T21:23:46.23337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became leader at term 2"} {"level":"info","ts":"2026-09-17T21:23:46.233377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 299bf3744060cbd elected leader 299bf3744060cbd at term 2"} {"level":"info","ts":"2026-09-17T21:23:46.234568Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T21:23:46.235381Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"299bf3744060cbd","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.111:2379]}","request-path":"/0/members/299bf3744060cbd/attributes","cluster-id":"83d94f04ddaf7996","publish-timeout":"7s"} {"level":"info","ts":"2026-09-17T21:23:46.235383Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-17T21:23:46.235409Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-17T21:23:46.235671Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-17T21:23:46.23579Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-17T21:23:46.237238Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-17T21:23:46.237843Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.111:2379"} {"level":"info","ts":"2026-09-17T21:23:46.240164Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"83d94f04ddaf7996","local-member-id":"299bf3744060cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T21:23:46.240285Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T21:23:46.240335Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T21:23:49.275229Z","caller":"traceutil/trace.go:171","msg":"trace[910896921] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"177.696995ms","start":"2026-09-17T21:23:49.097505Z","end":"2026-09-17T21:23:49.275202Z","steps":["trace[910896921] 'process raft request' (duration: 118.61234ms)","trace[910896921] 'compare' (duration: 58.953311ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:24:25.16762Z","caller":"traceutil/trace.go:171","msg":"trace[1734325333] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"147.844181ms","start":"2026-09-17T21:24:25.019749Z","end":"2026-09-17T21:24:25.167593Z","steps":["trace[1734325333] 'process raft request' (duration: 147.737999ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:25.167736Z","caller":"traceutil/trace.go:171","msg":"trace[1270536861] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"148.622311ms","start":"2026-09-17T21:24:25.019065Z","end":"2026-09-17T21:24:25.167688Z","steps":["trace[1270536861] 'process raft request' (duration: 136.952232ms)","trace[1270536861] 'compare' (duration: 11.31524ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:24:25.167958Z","caller":"traceutil/trace.go:171","msg":"trace[1190120560] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"145.524341ms","start":"2026-09-17T21:24:25.022356Z","end":"2026-09-17T21:24:25.16788Z","steps":["trace[1190120560] 'process raft request' (duration: 145.220534ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:25.168039Z","caller":"traceutil/trace.go:171","msg":"trace[1185702167] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"135.152174ms","start":"2026-09-17T21:24:25.032849Z","end":"2026-09-17T21:24:25.168001Z","steps":["trace[1185702167] 'process raft request' (duration: 134.9704ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:25.168056Z","caller":"traceutil/trace.go:171","msg":"trace[1496557644] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"148.158388ms","start":"2026-09-17T21:24:25.019871Z","end":"2026-09-17T21:24:25.168029Z","steps":["trace[1496557644] 'process raft request' (duration: 147.668686ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:25.168164Z","caller":"traceutil/trace.go:171","msg":"trace[1918895945] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"129.987152ms","start":"2026-09-17T21:24:25.038161Z","end":"2026-09-17T21:24:25.168148Z","steps":["trace[1918895945] 'process raft request' (duration: 129.802248ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:25.168272Z","caller":"traceutil/trace.go:171","msg":"trace[345694738] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"135.31968ms","start":"2026-09-17T21:24:25.032943Z","end":"2026-09-17T21:24:25.168263Z","steps":["trace[345694738] 'process raft request' (duration: 134.937869ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:25.168388Z","caller":"traceutil/trace.go:171","msg":"trace[1637689471] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"135.30099ms","start":"2026-09-17T21:24:25.033064Z","end":"2026-09-17T21:24:25.168365Z","steps":["trace[1637689471] 'process raft request' (duration: 134.854847ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:25.168309Z","caller":"traceutil/trace.go:171","msg":"trace[1761327727] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"135.541186ms","start":"2026-09-17T21:24:25.032751Z","end":"2026-09-17T21:24:25.168293Z","steps":["trace[1761327727] 'process raft request' (duration: 134.978221ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:25.168713Z","caller":"traceutil/trace.go:171","msg":"trace[690569070] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"142.087634ms","start":"2026-09-17T21:24:25.026605Z","end":"2026-09-17T21:24:25.168693Z","steps":["trace[690569070] 'process raft request' (duration: 141.085388ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:26.825219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.593044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T21:24:26.82557Z","caller":"traceutil/trace.go:171","msg":"trace[2068024415] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"318.973912ms","start":"2026-09-17T21:24:26.506571Z","end":"2026-09-17T21:24:26.825544Z","steps":["trace[2068024415] 'process raft request' (duration: 318.852669ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:26.825682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:26.506538Z","time spent":"319.092975ms","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T21:24:26.825947Z","caller":"traceutil/trace.go:171","msg":"trace[2079446824] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"366.291884ms","start":"2026-09-17T21:24:26.459612Z","end":"2026-09-17T21:24:26.825904Z","steps":["trace[2079446824] 'process raft request' (duration: 33.335605ms)","trace[2079446824] 'compare' (duration: 331.39143ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:24:26.826203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:26.459589Z","time spent":"366.47076ms","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T21:24:26.826299Z","caller":"traceutil/trace.go:171","msg":"trace[60959392] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"360.339873ms","start":"2026-09-17T21:24:26.465916Z","end":"2026-09-17T21:24:26.826256Z","steps":["trace[60959392] 'read index received' (duration: 26.913881ms)","trace[60959392] 'applied index is now lower than readState.Index' (duration: 333.422712ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:24:26.826397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.6532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:24:26.826473Z","caller":"traceutil/trace.go:171","msg":"trace[225658584] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:567; }","duration":"383.760383ms","start":"2026-09-17T21:24:26.442693Z","end":"2026-09-17T21:24:26.826453Z","steps":["trace[225658584] 'agreement among raft nodes before linearized reading' (duration: 383.62014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:26.826482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.741962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T21:24:26.826507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.470614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T21:24:26.826534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:26.442676Z","time spent":"383.841085ms","remote":"127.0.0.1:42602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-09-17T21:24:26.826577Z","caller":"traceutil/trace.go:171","msg":"trace[1649755763] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:567; }","duration":"411.566046ms","start":"2026-09-17T21:24:26.414996Z","end":"2026-09-17T21:24:26.826562Z","steps":["trace[1649755763] 'agreement among raft nodes before linearized reading' (duration: 411.442123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:26.826598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.12735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T21:24:26.826622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:26.414987Z","time spent":"411.624278ms","remote":"127.0.0.1:42586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"info","ts":"2026-09-17T21:24:26.826635Z","caller":"traceutil/trace.go:171","msg":"trace[1809041555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"201.18085ms","start":"2026-09-17T21:24:26.625443Z","end":"2026-09-17T21:24:26.826624Z","steps":["trace[1809041555] 'agreement among raft nodes before linearized reading' (duration: 201.125659ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:26.826833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.395459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:24:26.82689Z","caller":"traceutil/trace.go:171","msg":"trace[443978594] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:567; }","duration":"361.469392ms","start":"2026-09-17T21:24:26.465406Z","end":"2026-09-17T21:24:26.826875Z","steps":["trace[443978594] 'agreement among raft nodes before linearized reading' (duration: 361.353969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:26.826942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:26.465398Z","time spent":"361.529903ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-17T21:24:26.827035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.686926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-09-17T21:24:26.8271Z","caller":"traceutil/trace.go:171","msg":"trace[1749180446] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:567; }","duration":"407.766259ms","start":"2026-09-17T21:24:26.419317Z","end":"2026-09-17T21:24:26.827083Z","steps":["trace[1749180446] 'agreement among raft nodes before linearized reading' (duration: 407.548663ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:26.82715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:26.419309Z","time spent":"407.82954ms","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":98736,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-09-17T21:24:26.827179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.783539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:24:26.827225Z","caller":"traceutil/trace.go:171","msg":"trace[1699714068] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:567; }","duration":"361.890302ms","start":"2026-09-17T21:24:26.465321Z","end":"2026-09-17T21:24:26.827211Z","steps":["trace[1699714068] 'agreement among raft nodes before linearized reading' (duration: 361.81672ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:26.827275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:26.465308Z","time spent":"361.952983ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-09-17T21:24:26.827363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.065579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:24:26.827602Z","caller":"traceutil/trace.go:171","msg":"trace[1030155823] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:567; }","duration":"412.543731ms","start":"2026-09-17T21:24:26.415045Z","end":"2026-09-17T21:24:26.827589Z","steps":["trace[1030155823] 'agreement among raft nodes before linearized reading' (duration: 412.278104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:26.827644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:26.415019Z","time spent":"412.612144ms","remote":"127.0.0.1:42586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-09-17T21:24:26.827649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.264883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:24:26.827698Z","caller":"traceutil/trace.go:171","msg":"trace[497687672] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:567; }","duration":"362.340844ms","start":"2026-09-17T21:24:26.465343Z","end":"2026-09-17T21:24:26.827684Z","steps":["trace[497687672] 'agreement among raft nodes before linearized reading' (duration: 362.263922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:26.827752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:26.465306Z","time spent":"362.429866ms","remote":"127.0.0.1:42602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"info","ts":"2026-09-17T21:24:26.82653Z","caller":"traceutil/trace.go:171","msg":"trace[938268299] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:567; }","duration":"383.821625ms","start":"2026-09-17T21:24:26.442692Z","end":"2026-09-17T21:24:26.826514Z","steps":["trace[938268299] 'agreement among raft nodes before linearized reading' (duration: 383.731052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:26.827895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:26.442675Z","time spent":"385.1981ms","remote":"127.0.0.1:42602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-09-17T21:24:26.828344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.6406ms","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-17T21:24:26.828393Z","caller":"traceutil/trace.go:171","msg":"trace[1968138059] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:567; }","duration":"199.701301ms","start":"2026-09-17T21:24:26.628681Z","end":"2026-09-17T21:24:26.828382Z","steps":["trace[1968138059] 'agreement among raft nodes before linearized reading' (duration: 199.629669ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:26.828558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.878185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:24:26.828593Z","caller":"traceutil/trace.go:171","msg":"trace[1116507364] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:567; }","duration":"199.924517ms","start":"2026-09-17T21:24:26.628659Z","end":"2026-09-17T21:24:26.828583Z","steps":["trace[1116507364] 'agreement among raft nodes before linearized reading' (duration: 199.871086ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:31.722399Z","caller":"traceutil/trace.go:171","msg":"trace[1962179221] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"100.7943ms","start":"2026-09-17T21:24:31.621559Z","end":"2026-09-17T21:24:31.722354Z","steps":["trace[1962179221] 'process raft request' (duration: 98.88298ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:32.392444Z","caller":"traceutil/trace.go:171","msg":"trace[508319358] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"393.159186ms","start":"2026-09-17T21:24:31.999255Z","end":"2026-09-17T21:24:32.392414Z","steps":["trace[508319358] 'read index received' (duration: 392.984741ms)","trace[508319358] 'applied index is now lower than readState.Index' (duration: 173.625µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:24:32.392534Z","caller":"traceutil/trace.go:171","msg":"trace[1744221758] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"620.425144ms","start":"2026-09-17T21:24:31.772101Z","end":"2026-09-17T21:24:32.392526Z","steps":["trace[1744221758] 'process raft request' (duration: 620.207419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:32.392614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:31.772082Z","time spent":"620.478596ms","remote":"127.0.0.1:42964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T21:24:32.392904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.296313ms","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-17T21:24:32.392944Z","caller":"traceutil/trace.go:171","msg":"trace[1680822979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"201.388705ms","start":"2026-09-17T21:24:32.191533Z","end":"2026-09-17T21:24:32.392922Z","steps":["trace[1680822979] 'agreement among raft nodes before linearized reading' (duration: 201.271542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:32.392984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.565545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-17T21:24:32.392988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.546464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vpwb2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-17T21:24:32.393108Z","caller":"traceutil/trace.go:171","msg":"trace[2128772797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"393.748441ms","start":"2026-09-17T21:24:31.999337Z","end":"2026-09-17T21:24:32.393086Z","steps":["trace[2128772797] 'agreement among raft nodes before linearized reading' (duration: 393.308519ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:32.39319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.802532ms","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-17T21:24:32.393272Z","caller":"traceutil/trace.go:171","msg":"trace[1149203113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"394.046159ms","start":"2026-09-17T21:24:31.999214Z","end":"2026-09-17T21:24:32.39326Z","steps":["trace[1149203113] 'agreement among raft nodes before linearized reading' (duration: 393.674898ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:32.393352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:31.99919Z","time spent":"394.154622ms","remote":"127.0.0.1:43048","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-17T21:24:32.393194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:24:31.99933Z","time spent":"393.852564ms","remote":"127.0.0.1:43048","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":"info","ts":"2026-09-17T21:24:32.393168Z","caller":"traceutil/trace.go:171","msg":"trace[744684944] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vpwb2; range_end:; response_count:1; response_revision:587; }","duration":"293.800602ms","start":"2026-09-17T21:24:32.099338Z","end":"2026-09-17T21:24:32.393139Z","steps":["trace[744684944] 'agreement among raft nodes before linearized reading' (duration: 293.510313ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:32.511423Z","caller":"traceutil/trace.go:171","msg":"trace[588704019] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"112.912483ms","start":"2026-09-17T21:24:32.398486Z","end":"2026-09-17T21:24:32.511399Z","steps":["trace[588704019] 'process raft request' (duration: 87.565452ms)","trace[588704019] 'compare' (duration: 25.234278ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:24:32.516489Z","caller":"traceutil/trace.go:171","msg":"trace[374922422] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:609; }","duration":"106.777266ms","start":"2026-09-17T21:24:32.409688Z","end":"2026-09-17T21:24:32.516465Z","steps":["trace[374922422] 'read index received' (duration: 76.423225ms)","trace[374922422] 'applied index is now lower than readState.Index' (duration: 30.352791ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:24:32.516638Z","caller":"traceutil/trace.go:171","msg":"trace[2040591020] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"113.049266ms","start":"2026-09-17T21:24:32.403541Z","end":"2026-09-17T21:24:32.51659Z","steps":["trace[2040591020] 'process raft request' (duration: 112.724088ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:32.516665Z","caller":"traceutil/trace.go:171","msg":"trace[333220630] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"103.302885ms","start":"2026-09-17T21:24:32.41335Z","end":"2026-09-17T21:24:32.516653Z","steps":["trace[333220630] 'process raft request' (duration: 103.006368ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:24:32.516846Z","caller":"traceutil/trace.go:171","msg":"trace[678813779] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"113.352474ms","start":"2026-09-17T21:24:32.403473Z","end":"2026-09-17T21:24:32.516825Z","steps":["trace[678813779] 'process raft request' (duration: 112.544882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:24:32.516941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.246217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:24:32.517052Z","caller":"traceutil/trace.go:171","msg":"trace[1856827943] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:591; }","duration":"107.411851ms","start":"2026-09-17T21:24:32.409621Z","end":"2026-09-17T21:24:32.517033Z","steps":["trace[1856827943] 'agreement among raft nodes before linearized reading' (duration: 107.219596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:25:20.375336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:25:20.065819Z","time spent":"309.513457ms","remote":"127.0.0.1:42798","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-17T21:26:12.079609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.260711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:26:12.079704Z","caller":"traceutil/trace.go:171","msg":"trace[2121539734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1876; }","duration":"455.437497ms","start":"2026-09-17T21:26:11.624237Z","end":"2026-09-17T21:26:12.079675Z","steps":["trace[2121539734] 'range keys from in-memory index tree' (duration: 455.076364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:26:12.079744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:26:11.624211Z","time spent":"455.52399ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-17T21:26:12.079923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.361784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eee67930-dcab-4b6f-bb18-dfcd98a7e72d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-17T21:26:12.079992Z","caller":"traceutil/trace.go:171","msg":"trace[1369249933] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eee67930-dcab-4b6f-bb18-dfcd98a7e72d; range_end:; response_count:1; response_revision:1876; }","duration":"455.644604ms","start":"2026-09-17T21:26:11.624326Z","end":"2026-09-17T21:26:12.07997Z","steps":["trace[1369249933] 'range keys from in-memory index tree' (duration: 455.153537ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:26:12.080085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:26:11.624296Z","time spent":"455.716756ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eee67930-dcab-4b6f-bb18-dfcd98a7e72d\" "} {"level":"info","ts":"2026-09-17T21:26:12.08015Z","caller":"traceutil/trace.go:171","msg":"trace[120948024] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"283.949361ms","start":"2026-09-17T21:26:11.796161Z","end":"2026-09-17T21:26:12.08011Z","steps":["trace[120948024] 'process raft request' (duration: 232.214595ms)","trace[120948024] 'compare' (duration: 51.163566ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:26:25.386388Z","caller":"traceutil/trace.go:171","msg":"trace[1965720901] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"143.751545ms","start":"2026-09-17T21:26:25.242594Z","end":"2026-09-17T21:26:25.386346Z","steps":["trace[1965720901] 'process raft request' (duration: 143.504367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:26:25.636984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.323919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T21:26:25.637268Z","caller":"traceutil/trace.go:171","msg":"trace[1764642796] linearizableReadLoop","detail":"{readStateIndex:2065; appliedIndex:2062; }","duration":"389.833839ms","start":"2026-09-17T21:26:25.247417Z","end":"2026-09-17T21:26:25.63725Z","steps":["trace[1764642796] 'read index received' (duration: 139.510458ms)","trace[1764642796] 'applied index is now lower than readState.Index' (duration: 250.321921ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:26:25.637281Z","caller":"traceutil/trace.go:171","msg":"trace[2044989448] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"392.698527ms","start":"2026-09-17T21:26:25.244528Z","end":"2026-09-17T21:26:25.637226Z","steps":["trace[2044989448] 'process raft request' (duration: 225.032166ms)","trace[2044989448] 'compare' (duration: 166.988808ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:26:25.637527Z","caller":"traceutil/trace.go:171","msg":"trace[1652128539] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"392.611464ms","start":"2026-09-17T21:26:25.244888Z","end":"2026-09-17T21:26:25.6375Z","steps":["trace[1652128539] 'process raft request' (duration: 392.19134ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:26:25.637602Z","caller":"traceutil/trace.go:171","msg":"trace[785588033] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"391.993373ms","start":"2026-09-17T21:26:25.245602Z","end":"2026-09-17T21:26:25.637595Z","steps":["trace[785588033] 'process raft request' (duration: 391.566289ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:26:25.637646Z","caller":"traceutil/trace.go:171","msg":"trace[1888848409] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"348.163927ms","start":"2026-09-17T21:26:25.289475Z","end":"2026-09-17T21:26:25.637639Z","steps":["trace[1888848409] 'process raft request' (duration: 347.719632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:26:25.637828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.407759ms","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-17T21:26:25.637866Z","caller":"traceutil/trace.go:171","msg":"trace[2114606978] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1991; }","duration":"390.46434ms","start":"2026-09-17T21:26:25.247389Z","end":"2026-09-17T21:26:25.637854Z","steps":["trace[2114606978] 'agreement among raft nodes before linearized reading' (duration: 390.280724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:26:25.637889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:26:25.247381Z","time spent":"390.501783ms","remote":"127.0.0.1:45612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-17T21:26:25.637873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:26:25.244516Z","time spent":"392.912594ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T21:26:25.637953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:26:25.244881Z","time spent":"392.691916ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T21:26:25.638109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:26:25.245588Z","time spent":"392.036515ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T21:26:25.638204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:26:25.289459Z","time spent":"348.201338ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T21:26:25.836026Z","caller":"traceutil/trace.go:171","msg":"trace[603225794] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"108.557304ms","start":"2026-09-17T21:26:25.727417Z","end":"2026-09-17T21:26:25.835974Z","steps":["trace[603225794] 'process raft request' (duration: 49.887552ms)","trace[603225794] 'compare' (duration: 58.497516ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:26:25.849249Z","caller":"traceutil/trace.go:171","msg":"trace[1009296699] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"119.383742ms","start":"2026-09-17T21:26:25.729837Z","end":"2026-09-17T21:26:25.84922Z","steps":["trace[1009296699] 'process raft request' (duration: 119.32344ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:26:25.849281Z","caller":"traceutil/trace.go:171","msg":"trace[150351066] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"121.187403ms","start":"2026-09-17T21:26:25.728033Z","end":"2026-09-17T21:26:25.84922Z","steps":["trace[150351066] 'process raft request' (duration: 120.895944ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:26:25.850335Z","caller":"traceutil/trace.go:171","msg":"trace[701220463] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"121.112091ms","start":"2026-09-17T21:26:25.729114Z","end":"2026-09-17T21:26:25.850226Z","steps":["trace[701220463] 'process raft request' (duration: 119.980052ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:26:25.997923Z","caller":"traceutil/trace.go:171","msg":"trace[1623113956] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"134.187898ms","start":"2026-09-17T21:26:25.863694Z","end":"2026-09-17T21:26:25.997882Z","steps":["trace[1623113956] 'process raft request' (duration: 104.303468ms)","trace[1623113956] 'compare' (duration: 29.710493ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:26:29.100712Z","caller":"traceutil/trace.go:171","msg":"trace[556716041] linearizableReadLoop","detail":"{readStateIndex:2090; appliedIndex:2089; }","duration":"170.507507ms","start":"2026-09-17T21:26:28.930171Z","end":"2026-09-17T21:26:29.100679Z","steps":["trace[556716041] 'read index received' (duration: 170.281489ms)","trace[556716041] 'applied index is now lower than readState.Index' (duration: 224.588µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:26:29.100874Z","caller":"traceutil/trace.go:171","msg":"trace[1809562407] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"233.698977ms","start":"2026-09-17T21:26:28.867158Z","end":"2026-09-17T21:26:29.100857Z","steps":["trace[1809562407] 'process raft request' (duration: 233.316865ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:26:29.102597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.351799ms","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-17T21:26:29.102729Z","caller":"traceutil/trace.go:171","msg":"trace[767768479] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2015; }","duration":"172.563437ms","start":"2026-09-17T21:26:28.930123Z","end":"2026-09-17T21:26:29.102687Z","steps":["trace[767768479] 'agreement among raft nodes before linearized reading' (duration: 171.129228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:26:29.462551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.577819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T21:26:29.46265Z","caller":"traceutil/trace.go:171","msg":"trace[1901763639] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2016; }","duration":"168.767255ms","start":"2026-09-17T21:26:29.29386Z","end":"2026-09-17T21:26:29.462627Z","steps":["trace[1901763639] 'count revisions from in-memory index tree' (duration: 168.460604ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:26:53.4342Z","caller":"traceutil/trace.go:171","msg":"trace[814174451] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2227; }","duration":"192.4306ms","start":"2026-09-17T21:26:53.241738Z","end":"2026-09-17T21:26:53.434169Z","steps":["trace[814174451] 'read index received' (duration: 192.140011ms)","trace[814174451] 'applied index is now lower than readState.Index' (duration: 289.409µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:26:53.434257Z","caller":"traceutil/trace.go:171","msg":"trace[1088242498] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"240.22536ms","start":"2026-09-17T21:26:53.194001Z","end":"2026-09-17T21:26:53.434227Z","steps":["trace[1088242498] 'process raft request' (duration: 239.973692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:26:53.434467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.701929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-17T21:26:53.434521Z","caller":"traceutil/trace.go:171","msg":"trace[426292980] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2148; }","duration":"192.784211ms","start":"2026-09-17T21:26:53.24172Z","end":"2026-09-17T21:26:53.434504Z","steps":["trace[426292980] 'agreement among raft nodes before linearized reading' (duration: 192.576715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:26:53.803745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.296891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T21:26:53.803904Z","caller":"traceutil/trace.go:171","msg":"trace[2067410456] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2228; }","duration":"182.249292ms","start":"2026-09-17T21:26:53.621626Z","end":"2026-09-17T21:26:53.803875Z","steps":["trace[2067410456] 'read index received' (duration: 74.023µs)","trace[2067410456] 'applied index is now lower than readState.Index' (duration: 182.173059ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:26:53.804049Z","caller":"traceutil/trace.go:171","msg":"trace[192902190] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"363.735359ms","start":"2026-09-17T21:26:53.440292Z","end":"2026-09-17T21:26:53.804027Z","steps":["trace[192902190] 'process raft request' (duration: 19.996914ms)","trace[192902190] 'compare' (duration: 341.965838ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:26:53.804141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:26:53.440262Z","time spent":"363.823772ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T21:26:53.804293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.667126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:26:53.804382Z","caller":"traceutil/trace.go:171","msg":"trace[1441951536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2149; }","duration":"178.80408ms","start":"2026-09-17T21:26:53.625556Z","end":"2026-09-17T21:26:53.80436Z","steps":["trace[1441951536] 'agreement among raft nodes before linearized reading' (duration: 178.677576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:26:53.804945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.311036ms","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-17T21:26:53.805007Z","caller":"traceutil/trace.go:171","msg":"trace[980491271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2149; }","duration":"183.460341ms","start":"2026-09-17T21:26:53.621527Z","end":"2026-09-17T21:26:53.804987Z","steps":["trace[980491271] 'agreement among raft nodes before linearized reading' (duration: 183.321757ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:26:54.091684Z","caller":"traceutil/trace.go:171","msg":"trace[136600807] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"279.272662ms","start":"2026-09-17T21:26:53.812373Z","end":"2026-09-17T21:26:54.091646Z","steps":["trace[136600807] 'process raft request' (duration: 278.808628ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:26:54.091824Z","caller":"traceutil/trace.go:171","msg":"trace[669024214] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"279.370976ms","start":"2026-09-17T21:26:53.812428Z","end":"2026-09-17T21:26:54.091799Z","steps":["trace[669024214] 'process raft request' (duration: 279.126628ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:26:54.092157Z","caller":"traceutil/trace.go:171","msg":"trace[618899544] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2230; }","duration":"186.002247ms","start":"2026-09-17T21:26:53.906083Z","end":"2026-09-17T21:26:54.092085Z","steps":["trace[618899544] 'read index received' (duration: 185.003625ms)","trace[618899544] 'applied index is now lower than readState.Index' (duration: 996.102µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:26:54.092159Z","caller":"traceutil/trace.go:171","msg":"trace[354131920] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"279.645995ms","start":"2026-09-17T21:26:53.812485Z","end":"2026-09-17T21:26:54.092131Z","steps":["trace[354131920] 'process raft request' (duration: 279.255881ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:26:54.092454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.344349ms","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-17T21:26:54.092531Z","caller":"traceutil/trace.go:171","msg":"trace[1064754073] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2153; }","duration":"186.489432ms","start":"2026-09-17T21:26:53.906016Z","end":"2026-09-17T21:26:54.092506Z","steps":["trace[1064754073] 'agreement among raft nodes before linearized reading' (duration: 186.188193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:28:58.736322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.380001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:28:58.736555Z","caller":"traceutil/trace.go:171","msg":"trace[2032049976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2921; }","duration":"111.550585ms","start":"2026-09-17T21:28:58.624875Z","end":"2026-09-17T21:28:58.736426Z","steps":["trace[2032049976] 'range keys from in-memory index tree' (duration: 111.308549ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:28:58.865054Z","caller":"traceutil/trace.go:171","msg":"trace[963998323] transaction","detail":"{read_only:false; response_revision:2922; number_of_response:1; }","duration":"124.279408ms","start":"2026-09-17T21:28:58.740753Z","end":"2026-09-17T21:28:58.865033Z","steps":["trace[963998323] 'process raft request' (duration: 124.158335ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:28:59.05432Z","caller":"traceutil/trace.go:171","msg":"trace[793767698] transaction","detail":"{read_only:false; response_revision:2923; number_of_response:1; }","duration":"313.166367ms","start":"2026-09-17T21:28:58.74111Z","end":"2026-09-17T21:28:59.054276Z","steps":["trace[793767698] 'process raft request' (duration: 312.985182ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:28:59.054496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:28:58.741101Z","time spent":"313.29264ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T21:28:59.05462Z","caller":"traceutil/trace.go:171","msg":"trace[1839454337] transaction","detail":"{read_only:false; response_revision:2924; number_of_response:1; }","duration":"313.505187ms","start":"2026-09-17T21:28:58.741102Z","end":"2026-09-17T21:28:59.054607Z","steps":["trace[1839454337] 'process raft request' (duration: 313.139176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:28:59.054671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:28:58.741077Z","time spent":"313.568469ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T21:28:59.054745Z","caller":"traceutil/trace.go:171","msg":"trace[1031755872] transaction","detail":"{read_only:false; response_revision:2926; number_of_response:1; }","duration":"221.012329ms","start":"2026-09-17T21:28:58.833725Z","end":"2026-09-17T21:28:59.054737Z","steps":["trace[1031755872] 'process raft request' (duration: 220.871955ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:28:59.05471Z","caller":"traceutil/trace.go:171","msg":"trace[208189] transaction","detail":"{read_only:false; response_revision:2925; number_of_response:1; }","duration":"277.42137ms","start":"2026-09-17T21:28:58.777255Z","end":"2026-09-17T21:28:59.054677Z","steps":["trace[208189] 'process raft request' (duration: 277.257395ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:28:59.054728Z","caller":"traceutil/trace.go:171","msg":"trace[1668513630] transaction","detail":"{read_only:false; response_revision:2927; number_of_response:1; }","duration":"216.481806ms","start":"2026-09-17T21:28:58.838222Z","end":"2026-09-17T21:28:59.054704Z","steps":["trace[1668513630] 'process raft request' (duration: 216.441595ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:28:59.162193Z","caller":"traceutil/trace.go:171","msg":"trace[77803063] transaction","detail":"{read_only:false; response_revision:2928; number_of_response:1; }","duration":"267.793247ms","start":"2026-09-17T21:28:58.89437Z","end":"2026-09-17T21:28:59.162163Z","steps":["trace[77803063] 'process raft request' (duration: 265.615433ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:45.683681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.734531ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-17T21:31:45.683817Z","caller":"traceutil/trace.go:171","msg":"trace[1758141992] linearizableReadLoop","detail":"{readStateIndex:4026; appliedIndex:4025; }","duration":"121.078331ms","start":"2026-09-17T21:31:45.562715Z","end":"2026-09-17T21:31:45.683794Z","steps":["trace[1758141992] 'read index received' (duration: 19.266388ms)","trace[1758141992] 'applied index is now lower than readState.Index' (duration: 101.809903ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:31:45.684068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.346958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-17T21:31:45.684112Z","caller":"traceutil/trace.go:171","msg":"trace[1838269341] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3878; }","duration":"121.447111ms","start":"2026-09-17T21:31:45.562649Z","end":"2026-09-17T21:31:45.684096Z","steps":["trace[1838269341] 'agreement among raft nodes before linearized reading' (duration: 121.252646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:47.823494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.93843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-17T21:31:47.824002Z","caller":"traceutil/trace.go:171","msg":"trace[998732002] transaction","detail":"{read_only:false; response_revision:3888; number_of_response:1; }","duration":"261.367639ms","start":"2026-09-17T21:31:47.56261Z","end":"2026-09-17T21:31:47.823977Z","steps":["trace[998732002] 'process raft request' (duration: 82.846894ms)","trace[998732002] 'compare' (duration: 177.566459ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:31:47.824176Z","caller":"traceutil/trace.go:171","msg":"trace[723459167] transaction","detail":"{read_only:false; response_revision:3889; number_of_response:1; }","duration":"124.824216ms","start":"2026-09-17T21:31:47.699327Z","end":"2026-09-17T21:31:47.824151Z","steps":["trace[723459167] 'process raft request' (duration: 124.297401ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:31:47.824532Z","caller":"traceutil/trace.go:171","msg":"trace[334516477] linearizableReadLoop","detail":"{readStateIndex:4036; appliedIndex:4035; }","duration":"195.1582ms","start":"2026-09-17T21:31:47.629359Z","end":"2026-09-17T21:31:47.824517Z","steps":["trace[334516477] 'read index received' (duration: 16.029718ms)","trace[334516477] 'applied index is now lower than readState.Index' (duration: 179.127212ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:31:47.824664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.322965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:31:47.824698Z","caller":"traceutil/trace.go:171","msg":"trace[2004774573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3890; }","duration":"195.403287ms","start":"2026-09-17T21:31:47.629282Z","end":"2026-09-17T21:31:47.824686Z","steps":["trace[2004774573] 'agreement among raft nodes before linearized reading' (duration: 195.337145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:47.824971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.665707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-17T21:31:47.825007Z","caller":"traceutil/trace.go:171","msg":"trace[347179827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3890; }","duration":"112.74932ms","start":"2026-09-17T21:31:47.712248Z","end":"2026-09-17T21:31:47.824998Z","steps":["trace[347179827] 'agreement among raft nodes before linearized reading' (duration: 112.631776ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:47.825071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.808151ms","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":"info","ts":"2026-09-17T21:31:47.825176Z","caller":"traceutil/trace.go:171","msg":"trace[1446526789] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:3890; }","duration":"102.960705ms","start":"2026-09-17T21:31:47.722191Z","end":"2026-09-17T21:31:47.825151Z","steps":["trace[1446526789] 'agreement among raft nodes before linearized reading' (duration: 102.724918ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:31:47.97966Z","caller":"traceutil/trace.go:171","msg":"trace[8296877] transaction","detail":"{read_only:false; response_revision:3892; number_of_response:1; }","duration":"108.228513ms","start":"2026-09-17T21:31:47.871392Z","end":"2026-09-17T21:31:47.97962Z","steps":["trace[8296877] 'process raft request' (duration: 68.585256ms)","trace[8296877] 'compare' (duration: 39.41121ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:31:48.614184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.143665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T21:31:48.614787Z","caller":"traceutil/trace.go:171","msg":"trace[1490863515] transaction","detail":"{read_only:false; response_revision:3895; number_of_response:1; }","duration":"448.19478ms","start":"2026-09-17T21:31:48.166566Z","end":"2026-09-17T21:31:48.61476Z","steps":["trace[1490863515] 'process raft request' (duration: 447.732898ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:48.61491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:31:48.166547Z","time spent":"448.292492ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T21:31:48.614986Z","caller":"traceutil/trace.go:171","msg":"trace[1643154071] transaction","detail":"{read_only:false; response_revision:3894; number_of_response:1; }","duration":"609.919078ms","start":"2026-09-17T21:31:48.004917Z","end":"2026-09-17T21:31:48.614836Z","steps":["trace[1643154071] 'process raft request' (duration: 372.016413ms)","trace[1643154071] 'compare' (duration: 236.985189ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:31:48.615298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:31:48.0049Z","time spent":"610.242347ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T21:31:48.614785Z","caller":"traceutil/trace.go:171","msg":"trace[327837633] linearizableReadLoop","detail":"{readStateIndex:4042; appliedIndex:4041; }","duration":"603.894559ms","start":"2026-09-17T21:31:48.010838Z","end":"2026-09-17T21:31:48.614732Z","steps":["trace[327837633] 'read index received' (duration: 366.184439ms)","trace[327837633] 'applied index is now lower than readState.Index' (duration: 237.70088ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:31:48.61552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.670231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret\" ","response":"range_response_count:1 size:1225"} {"level":"info","ts":"2026-09-17T21:31:48.61558Z","caller":"traceutil/trace.go:171","msg":"trace[1883575266] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret; range_end:; response_count:1; response_revision:3896; }","duration":"604.762794ms","start":"2026-09-17T21:31:48.0108Z","end":"2026-09-17T21:31:48.615562Z","steps":["trace[1883575266] 'agreement among raft nodes before linearized reading' (duration: 604.593838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:48.615638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:31:48.010784Z","time spent":"604.840686ms","remote":"127.0.0.1:37202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":1248,"request content":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret\" "} {"level":"warn","ts":"2026-09-17T21:31:48.616446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.24231ms","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-17T21:31:48.616507Z","caller":"traceutil/trace.go:171","msg":"trace[1713840962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3896; }","duration":"589.367373ms","start":"2026-09-17T21:31:48.027124Z","end":"2026-09-17T21:31:48.616491Z","steps":["trace[1713840962] 'agreement among raft nodes before linearized reading' (duration: 589.062535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:48.61666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.133456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:31:48.616723Z","caller":"traceutil/trace.go:171","msg":"trace[484647241] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:3896; }","duration":"144.25486ms","start":"2026-09-17T21:31:48.472448Z","end":"2026-09-17T21:31:48.616702Z","steps":["trace[484647241] 'agreement among raft nodes before linearized reading' (duration: 144.165828ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:48.616481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.172144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T21:31:48.617132Z","caller":"traceutil/trace.go:171","msg":"trace[1352641654] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3896; }","duration":"180.782251ms","start":"2026-09-17T21:31:48.436152Z","end":"2026-09-17T21:31:48.616934Z","steps":["trace[1352641654] 'agreement among raft nodes before linearized reading' (duration: 180.115802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:48.617165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.6477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:31:48.617246Z","caller":"traceutil/trace.go:171","msg":"trace[436853993] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:3896; }","duration":"575.762334ms","start":"2026-09-17T21:31:48.041469Z","end":"2026-09-17T21:31:48.617231Z","steps":["trace[436853993] 'agreement among raft nodes before linearized reading' (duration: 575.241628ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:48.617458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.559067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-09-17T21:31:48.617513Z","caller":"traceutil/trace.go:171","msg":"trace[1445859868] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:3896; }","duration":"527.692841ms","start":"2026-09-17T21:31:48.089806Z","end":"2026-09-17T21:31:48.617499Z","steps":["trace[1445859868] 'agreement among raft nodes before linearized reading' (duration: 526.875688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:48.617374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:31:48.041456Z","time spent":"575.826235ms","remote":"127.0.0.1:43012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-09-17T21:31:48.61756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:31:48.027105Z","time spent":"589.568949ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-17T21:31:48.617547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:31:48.089784Z","time spent":"527.752693ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-09-17T21:31:48.89827Z","caller":"traceutil/trace.go:171","msg":"trace[263969563] transaction","detail":"{read_only:false; response_revision:3901; number_of_response:1; }","duration":"207.859946ms","start":"2026-09-17T21:31:48.690374Z","end":"2026-09-17T21:31:48.898234Z","steps":["trace[263969563] 'process raft request' (duration: 129.783816ms)","trace[263969563] 'compare' (duration: 77.943437ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:31:51.744058Z","caller":"traceutil/trace.go:171","msg":"trace[1611893961] transaction","detail":"{read_only:false; response_revision:3913; number_of_response:1; }","duration":"147.281606ms","start":"2026-09-17T21:31:51.596752Z","end":"2026-09-17T21:31:51.744034Z","steps":["trace[1611893961] 'process raft request' (duration: 147.083421ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:31:54.030403Z","caller":"traceutil/trace.go:171","msg":"trace[519462285] linearizableReadLoop","detail":"{readStateIndex:4071; appliedIndex:4070; }","duration":"384.461639ms","start":"2026-09-17T21:31:53.645915Z","end":"2026-09-17T21:31:54.030377Z","steps":["trace[519462285] 'read index received' (duration: 384.356226ms)","trace[519462285] 'applied index is now lower than readState.Index' (duration: 104.123µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:31:54.030704Z","caller":"traceutil/trace.go:171","msg":"trace[126057168] transaction","detail":"{read_only:false; response_revision:3922; number_of_response:1; }","duration":"572.000471ms","start":"2026-09-17T21:31:53.458692Z","end":"2026-09-17T21:31:54.030693Z","steps":["trace[126057168] 'process raft request' (duration: 571.522088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:54.030803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:31:53.458671Z","time spent":"572.059822ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T21:31:54.031492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.57315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-09-17T21:31:54.031546Z","caller":"traceutil/trace.go:171","msg":"trace[1733015262] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3922; }","duration":"385.660812ms","start":"2026-09-17T21:31:53.645868Z","end":"2026-09-17T21:31:54.031529Z","steps":["trace[1733015262] 'agreement among raft nodes before linearized reading' (duration: 385.110016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:54.031579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:31:53.645827Z","time spent":"385.744594ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":721,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret\" "} {"level":"info","ts":"2026-09-17T21:31:54.145893Z","caller":"traceutil/trace.go:171","msg":"trace[1643128110] transaction","detail":"{read_only:false; response_revision:3923; number_of_response:1; }","duration":"263.980731ms","start":"2026-09-17T21:31:53.881877Z","end":"2026-09-17T21:31:54.145858Z","steps":["trace[1643128110] 'process raft request' (duration: 223.649583ms)","trace[1643128110] 'compare' (duration: 40.122202ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:31:54.146132Z","caller":"traceutil/trace.go:171","msg":"trace[917930780] linearizableReadLoop","detail":"{readStateIndex:4073; appliedIndex:4071; }","duration":"115.654165ms","start":"2026-09-17T21:31:54.030468Z","end":"2026-09-17T21:31:54.146122Z","steps":["trace[917930780] 'read index received' (duration: 75.08233ms)","trace[917930780] 'applied index is now lower than readState.Index' (duration: 40.570895ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:31:54.146343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.093098ms","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-17T21:31:54.146387Z","caller":"traceutil/trace.go:171","msg":"trace[1696884926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3924; }","duration":"399.178511ms","start":"2026-09-17T21:31:53.747195Z","end":"2026-09-17T21:31:54.146374Z","steps":["trace[1696884926] 'agreement among raft nodes before linearized reading' (duration: 398.960675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:54.146417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:31:53.74718Z","time spent":"399.229432ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-17T21:31:54.146607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.696079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-17T21:31:54.146639Z","caller":"traceutil/trace.go:171","msg":"trace[502679817] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3924; }","duration":"143.757791ms","start":"2026-09-17T21:31:54.002873Z","end":"2026-09-17T21:31:54.146631Z","steps":["trace[502679817] 'agreement among raft nodes before linearized reading' (duration: 143.676308ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:31:54.146568Z","caller":"traceutil/trace.go:171","msg":"trace[748385087] transaction","detail":"{read_only:false; response_revision:3924; number_of_response:1; }","duration":"245.602487ms","start":"2026-09-17T21:31:53.900904Z","end":"2026-09-17T21:31:54.146507Z","steps":["trace[748385087] 'process raft request' (duration: 244.894467ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:54.146672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.389288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:31:54.146772Z","caller":"traceutil/trace.go:171","msg":"trace[854339645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3924; }","duration":"247.526221ms","start":"2026-09-17T21:31:53.899222Z","end":"2026-09-17T21:31:54.146749Z","steps":["trace[854339645] 'agreement among raft nodes before linearized reading' (duration: 247.358776ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:56.342337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.312166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T21:31:56.343073Z","caller":"traceutil/trace.go:171","msg":"trace[353910806] transaction","detail":"{read_only:false; response_revision:3933; number_of_response:1; }","duration":"189.298406ms","start":"2026-09-17T21:31:56.153741Z","end":"2026-09-17T21:31:56.34304Z","steps":["trace[353910806] 'compare' (duration: 184.144011ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:31:56.34341Z","caller":"traceutil/trace.go:171","msg":"trace[778657933] transaction","detail":"{read_only:false; response_revision:3934; number_of_response:1; }","duration":"189.608964ms","start":"2026-09-17T21:31:56.153783Z","end":"2026-09-17T21:31:56.343392Z","steps":["trace[778657933] 'process raft request' (duration: 189.288726ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:31:56.343667Z","caller":"traceutil/trace.go:171","msg":"trace[1870372219] linearizableReadLoop","detail":"{readStateIndex:4084; appliedIndex:4082; }","duration":"185.241052ms","start":"2026-09-17T21:31:56.157974Z","end":"2026-09-17T21:31:56.343215Z","steps":["trace[1870372219] 'read index received' (duration: 39.28757ms)","trace[1870372219] 'applied index is now lower than readState.Index' (duration: 145.952432ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:31:56.343922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.90471ms","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-17T21:31:56.344024Z","caller":"traceutil/trace.go:171","msg":"trace[95855651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3934; }","duration":"186.035154ms","start":"2026-09-17T21:31:56.157966Z","end":"2026-09-17T21:31:56.344001Z","steps":["trace[95855651] 'agreement among raft nodes before linearized reading' (duration: 185.678624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:31:56.344256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.27213ms","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-17T21:31:56.344328Z","caller":"traceutil/trace.go:171","msg":"trace[1855137385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3934; }","duration":"171.389294ms","start":"2026-09-17T21:31:56.172922Z","end":"2026-09-17T21:31:56.344312Z","steps":["trace[1855137385] 'agreement among raft nodes before linearized reading' (duration: 171.111476ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:31:56.529454Z","caller":"traceutil/trace.go:171","msg":"trace[1368514293] transaction","detail":"{read_only:false; response_revision:3935; number_of_response:1; }","duration":"179.884432ms","start":"2026-09-17T21:31:56.349536Z","end":"2026-09-17T21:31:56.52942Z","steps":["trace[1368514293] 'process raft request' (duration: 165.091038ms)","trace[1368514293] 'compare' (duration: 14.28971ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:31:56.541174Z","caller":"traceutil/trace.go:171","msg":"trace[99996675] transaction","detail":"{read_only:false; response_revision:3936; number_of_response:1; }","duration":"191.248249ms","start":"2026-09-17T21:31:56.349901Z","end":"2026-09-17T21:31:56.54115Z","steps":["trace[99996675] 'process raft request' (duration: 191.047234ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:31:57.055496Z","caller":"traceutil/trace.go:171","msg":"trace[1628183308] transaction","detail":"{read_only:false; response_revision:3941; number_of_response:1; }","duration":"174.655555ms","start":"2026-09-17T21:31:56.880813Z","end":"2026-09-17T21:31:57.055468Z","steps":["trace[1628183308] 'process raft request' (duration: 174.600914ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:31:57.055563Z","caller":"traceutil/trace.go:171","msg":"trace[333769282] transaction","detail":"{read_only:false; response_revision:3940; number_of_response:1; }","duration":"189.219923ms","start":"2026-09-17T21:31:56.866309Z","end":"2026-09-17T21:31:57.055529Z","steps":["trace[333769282] 'process raft request' (duration: 120.637385ms)","trace[333769282] 'compare' (duration: 68.30511ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:31:58.695493Z","caller":"traceutil/trace.go:171","msg":"trace[1264376162] transaction","detail":"{read_only:false; response_revision:3946; number_of_response:1; }","duration":"123.719592ms","start":"2026-09-17T21:31:58.571746Z","end":"2026-09-17T21:31:58.695465Z","steps":["trace[1264376162] 'process raft request' (duration: 79.851494ms)","trace[1264376162] 'compare' (duration: 43.746455ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:31:59.201148Z","caller":"traceutil/trace.go:171","msg":"trace[1975385635] transaction","detail":"{read_only:false; response_revision:3951; number_of_response:1; }","duration":"136.50191ms","start":"2026-09-17T21:31:59.06459Z","end":"2026-09-17T21:31:59.201092Z","steps":["trace[1975385635] 'process raft request' (duration: 135.023689ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:32:00.297595Z","caller":"traceutil/trace.go:171","msg":"trace[1393296580] transaction","detail":"{read_only:false; response_revision:3954; number_of_response:1; }","duration":"108.167227ms","start":"2026-09-17T21:32:00.189407Z","end":"2026-09-17T21:32:00.297574Z","steps":["trace[1393296580] 'process raft request' (duration: 49.553756ms)","trace[1393296580] 'compare' (duration: 58.530399ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:32:00.697195Z","caller":"traceutil/trace.go:171","msg":"trace[242716883] transaction","detail":"{read_only:false; response_revision:3957; number_of_response:1; }","duration":"108.450245ms","start":"2026-09-17T21:32:00.588717Z","end":"2026-09-17T21:32:00.697167Z","steps":["trace[242716883] 'process raft request' (duration: 108.304091ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:32:02.861617Z","caller":"traceutil/trace.go:171","msg":"trace[1326118727] transaction","detail":"{read_only:false; response_revision:3969; number_of_response:1; }","duration":"106.672826ms","start":"2026-09-17T21:32:02.754907Z","end":"2026-09-17T21:32:02.86158Z","steps":["trace[1326118727] 'process raft request' (duration: 50.333199ms)","trace[1326118727] 'compare' (duration: 55.866305ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:32:03.224747Z","caller":"traceutil/trace.go:171","msg":"trace[2025633741] transaction","detail":"{read_only:false; response_revision:3972; number_of_response:1; }","duration":"121.481062ms","start":"2026-09-17T21:32:03.103238Z","end":"2026-09-17T21:32:03.224719Z","steps":["trace[2025633741] 'process raft request' (duration: 41.626526ms)","trace[2025633741] 'compare' (duration: 79.729823ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:32:03.395522Z","caller":"traceutil/trace.go:171","msg":"trace[1903920294] transaction","detail":"{read_only:false; response_revision:3973; number_of_response:1; }","duration":"110.939477ms","start":"2026-09-17T21:32:03.284542Z","end":"2026-09-17T21:32:03.395481Z","steps":["trace[1903920294] 'process raft request' (duration: 83.597141ms)","trace[1903920294] 'compare' (duration: 26.111961ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:32:05.169412Z","caller":"traceutil/trace.go:171","msg":"trace[898061698] linearizableReadLoop","detail":"{readStateIndex:4131; appliedIndex:4130; }","duration":"133.011975ms","start":"2026-09-17T21:32:05.036362Z","end":"2026-09-17T21:32:05.169374Z","steps":["trace[898061698] 'read index received' (duration: 46.135502ms)","trace[898061698] 'applied index is now lower than readState.Index' (duration: 86.875203ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:32:05.169766Z","caller":"traceutil/trace.go:171","msg":"trace[2088541498] transaction","detail":"{read_only:false; response_revision:3980; number_of_response:1; }","duration":"276.608367ms","start":"2026-09-17T21:32:04.893137Z","end":"2026-09-17T21:32:05.169746Z","steps":["trace[2088541498] 'process raft request' (duration: 189.437305ms)","trace[2088541498] 'compare' (duration: 86.629717ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:32:05.170128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.751827ms","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-17T21:32:05.170184Z","caller":"traceutil/trace.go:171","msg":"trace[1311904469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3980; }","duration":"133.888881ms","start":"2026-09-17T21:32:05.036278Z","end":"2026-09-17T21:32:05.170167Z","steps":["trace[1311904469] 'agreement among raft nodes before linearized reading' (duration: 133.227203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:32:07.539918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.921891ms","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-17T21:32:07.540688Z","caller":"traceutil/trace.go:171","msg":"trace[1146578826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3994; }","duration":"120.803185ms","start":"2026-09-17T21:32:07.419858Z","end":"2026-09-17T21:32:07.540662Z","steps":["trace[1146578826] 'range keys from in-memory index tree' (duration: 119.601631ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:32:09.001594Z","caller":"traceutil/trace.go:171","msg":"trace[1788204311] transaction","detail":"{read_only:false; response_revision:3999; number_of_response:1; }","duration":"140.720193ms","start":"2026-09-17T21:32:08.860837Z","end":"2026-09-17T21:32:09.001557Z","steps":["trace[1788204311] 'process raft request' (duration: 140.484217ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:32:09.146926Z","caller":"traceutil/trace.go:171","msg":"trace[1769989421] transaction","detail":"{read_only:false; response_revision:4000; number_of_response:1; }","duration":"119.53813ms","start":"2026-09-17T21:32:09.027334Z","end":"2026-09-17T21:32:09.146872Z","steps":["trace[1769989421] 'process raft request' (duration: 26.379999ms)","trace[1769989421] 'compare' (duration: 91.585627ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:32:09.147484Z","caller":"traceutil/trace.go:171","msg":"trace[1660560852] transaction","detail":"{read_only:false; response_revision:4001; number_of_response:1; }","duration":"108.44119ms","start":"2026-09-17T21:32:09.039011Z","end":"2026-09-17T21:32:09.147452Z","steps":["trace[1660560852] 'process raft request' (duration: 107.653377ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:20.912917Z","caller":"traceutil/trace.go:171","msg":"trace[1950179044] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"231.753705ms","start":"2026-09-17T21:33:20.681101Z","end":"2026-09-17T21:33:20.912855Z","steps":["trace[1950179044] 'process raft request' (duration: 231.607981ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:20.920862Z","caller":"traceutil/trace.go:171","msg":"trace[2120122609] linearizableReadLoop","detail":"{readStateIndex:5046; appliedIndex:5044; }","duration":"235.022888ms","start":"2026-09-17T21:33:20.68582Z","end":"2026-09-17T21:33:20.920842Z","steps":["trace[2120122609] 'read index received' (duration: 226.905759ms)","trace[2120122609] 'applied index is now lower than readState.Index' (duration: 8.115789ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:33:20.920829Z","caller":"traceutil/trace.go:171","msg":"trace[2062581366] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"235.209803ms","start":"2026-09-17T21:33:20.685564Z","end":"2026-09-17T21:33:20.920774Z","steps":["trace[2062581366] 'process raft request' (duration: 234.859904ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:20.920923Z","caller":"traceutil/trace.go:171","msg":"trace[1521575103] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"231.649632ms","start":"2026-09-17T21:33:20.689244Z","end":"2026-09-17T21:33:20.920894Z","steps":["trace[1521575103] 'process raft request' (duration: 231.333023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:33:20.921397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.551973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-17T21:33:20.921448Z","caller":"traceutil/trace.go:171","msg":"trace[1697483597] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4858; }","duration":"235.647945ms","start":"2026-09-17T21:33:20.685788Z","end":"2026-09-17T21:33:20.921436Z","steps":["trace[1697483597] 'agreement among raft nodes before linearized reading' (duration: 235.432209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:33:20.921839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.361587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:46476"} {"level":"info","ts":"2026-09-17T21:33:20.921893Z","caller":"traceutil/trace.go:171","msg":"trace[381708223] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4858; }","duration":"235.4619ms","start":"2026-09-17T21:33:20.686421Z","end":"2026-09-17T21:33:20.921883Z","steps":["trace[381708223] 'agreement among raft nodes before linearized reading' (duration: 235.157101ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:27.248829Z","caller":"traceutil/trace.go:171","msg":"trace[2073404777] linearizableReadLoop","detail":"{readStateIndex:5203; appliedIndex:5202; }","duration":"102.306082ms","start":"2026-09-17T21:33:27.146498Z","end":"2026-09-17T21:33:27.248804Z","steps":["trace[2073404777] 'read index received' (duration: 83.811739ms)","trace[2073404777] 'applied index is now lower than readState.Index' (duration: 18.492663ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:33:27.248998Z","caller":"traceutil/trace.go:171","msg":"trace[869898939] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"104.631247ms","start":"2026-09-17T21:33:27.144312Z","end":"2026-09-17T21:33:27.248943Z","steps":["trace[869898939] 'process raft request' (duration: 86.091813ms)","trace[869898939] 'compare' (duration: 18.265546ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:33:27.24904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.511758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T21:33:27.249178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.035216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-17T21:33:27.24921Z","caller":"traceutil/trace.go:171","msg":"trace[1305329503] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5010; }","duration":"101.084496ms","start":"2026-09-17T21:33:27.148114Z","end":"2026-09-17T21:33:27.249198Z","steps":["trace[1305329503] 'agreement among raft nodes before linearized reading' (duration: 100.956293ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:27.249257Z","caller":"traceutil/trace.go:171","msg":"trace[1773119504] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:5010; }","duration":"102.702953ms","start":"2026-09-17T21:33:27.146476Z","end":"2026-09-17T21:33:27.249179Z","steps":["trace[1773119504] 'agreement among raft nodes before linearized reading' (duration: 102.476116ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:28.916275Z","caller":"traceutil/trace.go:171","msg":"trace[1710106216] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"141.530729ms","start":"2026-09-17T21:33:28.774713Z","end":"2026-09-17T21:33:28.916244Z","steps":["trace[1710106216] 'process raft request' (duration: 141.367365ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:34.67116Z","caller":"traceutil/trace.go:171","msg":"trace[1922257736] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"295.731692ms","start":"2026-09-17T21:33:34.375397Z","end":"2026-09-17T21:33:34.671129Z","steps":["trace[1922257736] 'process raft request' (duration: 295.485414ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:34.923757Z","caller":"traceutil/trace.go:171","msg":"trace[1602108013] linearizableReadLoop","detail":"{readStateIndex:5269; appliedIndex:5268; }","duration":"117.881873ms","start":"2026-09-17T21:33:34.805848Z","end":"2026-09-17T21:33:34.92373Z","steps":["trace[1602108013] 'read index received' (duration: 97.611681ms)","trace[1602108013] 'applied index is now lower than readState.Index' (duration: 20.267882ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:33:34.923969Z","caller":"traceutil/trace.go:171","msg":"trace[141034698] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"243.092832ms","start":"2026-09-17T21:33:34.680823Z","end":"2026-09-17T21:33:34.923916Z","steps":["trace[141034698] 'process raft request' (duration: 222.556502ms)","trace[141034698] 'compare' (duration: 19.638766ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:33:34.924326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.466619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16086"} {"level":"info","ts":"2026-09-17T21:33:34.924382Z","caller":"traceutil/trace.go:171","msg":"trace[1193148877] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5074; }","duration":"118.572553ms","start":"2026-09-17T21:33:34.805791Z","end":"2026-09-17T21:33:34.924364Z","steps":["trace[1193148877] 'agreement among raft nodes before linearized reading' (duration: 118.293494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:33:34.925703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.96167ms","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-17T21:33:34.925685Z","caller":"traceutil/trace.go:171","msg":"trace[1418678414] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"119.748685ms","start":"2026-09-17T21:33:34.805894Z","end":"2026-09-17T21:33:34.925643Z","steps":["trace[1418678414] 'process raft request' (duration: 119.271582ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:34.925812Z","caller":"traceutil/trace.go:171","msg":"trace[140244168] 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:5075; }","duration":"104.107374ms","start":"2026-09-17T21:33:34.821673Z","end":"2026-09-17T21:33:34.925781Z","steps":["trace[140244168] 'agreement among raft nodes before linearized reading' (duration: 103.767574ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:33:34.923405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.011549ms","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-17T21:33:34.928289Z","caller":"traceutil/trace.go:171","msg":"trace[2107510560] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5073; }","duration":"161.912747ms","start":"2026-09-17T21:33:34.766354Z","end":"2026-09-17T21:33:34.928267Z","steps":["trace[2107510560] 'range keys from in-memory index tree' (duration: 156.991789ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:35.297945Z","caller":"traceutil/trace.go:171","msg":"trace[420081006] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"167.791054ms","start":"2026-09-17T21:33:35.130113Z","end":"2026-09-17T21:33:35.297904Z","steps":["trace[420081006] 'process raft request' (duration: 167.527037ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:35.297999Z","caller":"traceutil/trace.go:171","msg":"trace[373932074] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"167.604338ms","start":"2026-09-17T21:33:35.130365Z","end":"2026-09-17T21:33:35.297969Z","steps":["trace[373932074] 'process raft request' (duration: 167.438463ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:35.298243Z","caller":"traceutil/trace.go:171","msg":"trace[319802700] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"167.836606ms","start":"2026-09-17T21:33:35.130393Z","end":"2026-09-17T21:33:35.298229Z","steps":["trace[319802700] 'process raft request' (duration: 167.464395ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:35.297997Z","caller":"traceutil/trace.go:171","msg":"trace[1613147778] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"167.496385ms","start":"2026-09-17T21:33:35.130473Z","end":"2026-09-17T21:33:35.29797Z","steps":["trace[1613147778] 'process raft request' (duration: 167.446703ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:35.389159Z","caller":"traceutil/trace.go:171","msg":"trace[1779423103] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"214.971878ms","start":"2026-09-17T21:33:35.174142Z","end":"2026-09-17T21:33:35.389114Z","steps":["trace[1779423103] 'process raft request' (duration: 214.728441ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:33:35.491776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.711755ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-17T21:33:35.49213Z","caller":"traceutil/trace.go:171","msg":"trace[1076877475] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"244.216995ms","start":"2026-09-17T21:33:35.247865Z","end":"2026-09-17T21:33:35.492082Z","steps":["trace[1076877475] 'process raft request' (duration: 243.993539ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:35.492136Z","caller":"traceutil/trace.go:171","msg":"trace[1455620490] linearizableReadLoop","detail":"{readStateIndex:5290; appliedIndex:5287; }","duration":"187.263915ms","start":"2026-09-17T21:33:35.304848Z","end":"2026-09-17T21:33:35.492112Z","steps":["trace[1455620490] 'read index received' (duration: 83.990814ms)","trace[1455620490] 'applied index is now lower than readState.Index' (duration: 103.271121ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:33:35.531978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.102521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-17T21:33:35.532048Z","caller":"traceutil/trace.go:171","msg":"trace[293656038] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5094; }","duration":"227.229656ms","start":"2026-09-17T21:33:35.304802Z","end":"2026-09-17T21:33:35.532031Z","steps":["trace[293656038] 'agreement among raft nodes before linearized reading' (duration: 187.539983ms)","trace[293656038] 'range keys from in-memory index tree' (duration: 39.493167ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:33:35.532313Z","caller":"traceutil/trace.go:171","msg":"trace[254598727] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"222.309006ms","start":"2026-09-17T21:33:35.309975Z","end":"2026-09-17T21:33:35.532284Z","steps":["trace[254598727] 'process raft request' (duration: 222.029067ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:35.532445Z","caller":"traceutil/trace.go:171","msg":"trace[1693887348] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"223.578482ms","start":"2026-09-17T21:33:35.308856Z","end":"2026-09-17T21:33:35.532434Z","steps":["trace[1693887348] 'process raft request' (duration: 183.246521ms)","trace[1693887348] 'compare' (duration: 39.528078ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:33:35.532341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.256086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-17T21:33:35.532516Z","caller":"traceutil/trace.go:171","msg":"trace[1195377444] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5097; }","duration":"227.449512ms","start":"2026-09-17T21:33:35.305056Z","end":"2026-09-17T21:33:35.532505Z","steps":["trace[1195377444] 'agreement among raft nodes before linearized reading' (duration: 227.223726ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:35.673177Z","caller":"traceutil/trace.go:171","msg":"trace[359579082] linearizableReadLoop","detail":"{readStateIndex:5298; appliedIndex:5296; }","duration":"120.664431ms","start":"2026-09-17T21:33:35.552489Z","end":"2026-09-17T21:33:35.673154Z","steps":["trace[359579082] 'read index received' (duration: 6.331559ms)","trace[359579082] 'applied index is now lower than readState.Index' (duration: 114.331442ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:33:35.673471Z","caller":"traceutil/trace.go:171","msg":"trace[1248258814] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"121.502455ms","start":"2026-09-17T21:33:35.551957Z","end":"2026-09-17T21:33:35.673459Z","steps":["trace[1248258814] 'process raft request' (duration: 121.141655ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:35.673479Z","caller":"traceutil/trace.go:171","msg":"trace[2086276705] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"122.664709ms","start":"2026-09-17T21:33:35.550801Z","end":"2026-09-17T21:33:35.673466Z","steps":["trace[2086276705] 'process raft request' (duration: 122.161204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:33:35.673741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.235419ms","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-17T21:33:35.673774Z","caller":"traceutil/trace.go:171","msg":"trace[482486325] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5102; }","duration":"121.325581ms","start":"2026-09-17T21:33:35.55244Z","end":"2026-09-17T21:33:35.673765Z","steps":["trace[482486325] 'agreement among raft nodes before linearized reading' (duration: 121.190427ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:35.676495Z","caller":"traceutil/trace.go:171","msg":"trace[1575913413] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"113.304524ms","start":"2026-09-17T21:33:35.56318Z","end":"2026-09-17T21:33:35.676484Z","steps":["trace[1575913413] 'process raft request' (duration: 112.993554ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:38.060162Z","caller":"traceutil/trace.go:171","msg":"trace[615943719] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"202.585569ms","start":"2026-09-17T21:33:37.857548Z","end":"2026-09-17T21:33:38.060133Z","steps":["trace[615943719] 'process raft request' (duration: 202.403574ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:38.124821Z","caller":"traceutil/trace.go:171","msg":"trace[792598509] linearizableReadLoop","detail":"{readStateIndex:5371; appliedIndex:5369; }","duration":"244.728251ms","start":"2026-09-17T21:33:37.880061Z","end":"2026-09-17T21:33:38.12479Z","steps":["trace[792598509] 'read index received' (duration: 179.925719ms)","trace[792598509] 'applied index is now lower than readState.Index' (duration: 64.801412ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:33:38.124869Z","caller":"traceutil/trace.go:171","msg":"trace[1586967815] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"263.308826ms","start":"2026-09-17T21:33:37.861544Z","end":"2026-09-17T21:33:38.124852Z","steps":["trace[1586967815] 'process raft request' (duration: 263.071629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:33:38.125013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.362557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T21:33:38.125068Z","caller":"traceutil/trace.go:171","msg":"trace[866404900] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:5175; }","duration":"118.437179ms","start":"2026-09-17T21:33:38.006616Z","end":"2026-09-17T21:33:38.125053Z","steps":["trace[866404900] 'agreement among raft nodes before linearized reading' (duration: 118.335727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:33:38.125306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.239096ms","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-17T21:33:38.125984Z","caller":"traceutil/trace.go:171","msg":"trace[237910163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5175; }","duration":"245.440952ms","start":"2026-09-17T21:33:37.879988Z","end":"2026-09-17T21:33:38.125429Z","steps":["trace[237910163] 'agreement among raft nodes before linearized reading' (duration: 244.907346ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:43.895586Z","caller":"traceutil/trace.go:171","msg":"trace[715506621] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"108.427758ms","start":"2026-09-17T21:33:43.787137Z","end":"2026-09-17T21:33:43.895564Z","steps":["trace[715506621] 'process raft request' (duration: 106.427961ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:43.896013Z","caller":"traceutil/trace.go:171","msg":"trace[292989047] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"108.014446ms","start":"2026-09-17T21:33:43.787973Z","end":"2026-09-17T21:33:43.895987Z","steps":["trace[292989047] 'process raft request' (duration: 107.546413ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:46.262838Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2855} {"level":"info","ts":"2026-09-17T21:33:46.342176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2855,"took":"77.573884ms","hash":3916964579,"current-db-size-bytes":52490240,"current-db-size":"52 MB","current-db-size-in-use-bytes":27344896,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-17T21:33:46.342257Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3916964579,"revision":2855,"compact-revision":-1} {"level":"info","ts":"2026-09-17T21:33:49.323117Z","caller":"traceutil/trace.go:171","msg":"trace[681689323] linearizableReadLoop","detail":"{readStateIndex:5517; appliedIndex:5516; }","duration":"322.123277ms","start":"2026-09-17T21:33:49.000968Z","end":"2026-09-17T21:33:49.323091Z","steps":["trace[681689323] 'read index received' (duration: 322.004843ms)","trace[681689323] 'applied index is now lower than readState.Index' (duration: 117.364µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:33:49.323243Z","caller":"traceutil/trace.go:171","msg":"trace[343090801] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"363.333242ms","start":"2026-09-17T21:33:48.959895Z","end":"2026-09-17T21:33:49.323228Z","steps":["trace[343090801] 'process raft request' (duration: 363.019534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:33:49.323386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:33:48.959865Z","time spent":"363.413664ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T21:33:49.323501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.514468ms","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-17T21:33:49.323633Z","caller":"traceutil/trace.go:171","msg":"trace[791644409] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5315; }","duration":"322.686833ms","start":"2026-09-17T21:33:49.000918Z","end":"2026-09-17T21:33:49.323605Z","steps":["trace[791644409] 'agreement among raft nodes before linearized reading' (duration: 322.432755ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:33:49.323818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T21:33:49.000904Z","time spent":"322.898758ms","remote":"127.0.0.1:42918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-17T21:33:49.355083Z","caller":"traceutil/trace.go:171","msg":"trace[1389143444] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"197.977433ms","start":"2026-09-17T21:33:49.157082Z","end":"2026-09-17T21:33:49.35506Z","steps":["trace[1389143444] 'process raft request' (duration: 197.811898ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:49.610084Z","caller":"traceutil/trace.go:171","msg":"trace[508636151] linearizableReadLoop","detail":"{readStateIndex:5522; appliedIndex:5518; }","duration":"255.030247ms","start":"2026-09-17T21:33:49.35503Z","end":"2026-09-17T21:33:49.61006Z","steps":["trace[508636151] 'read index received' (duration: 247.00879ms)","trace[508636151] 'applied index is now lower than readState.Index' (duration: 8.020427ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:33:49.610191Z","caller":"traceutil/trace.go:171","msg":"trace[485719484] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"279.096359ms","start":"2026-09-17T21:33:49.331084Z","end":"2026-09-17T21:33:49.610181Z","steps":["trace[485719484] 'process raft request' (duration: 270.973988ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:49.610472Z","caller":"traceutil/trace.go:171","msg":"trace[392401732] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"278.560722ms","start":"2026-09-17T21:33:49.331902Z","end":"2026-09-17T21:33:49.610463Z","steps":["trace[392401732] 'process raft request' (duration: 277.988707ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:49.610742Z","caller":"traceutil/trace.go:171","msg":"trace[1267188136] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"278.662617ms","start":"2026-09-17T21:33:49.33207Z","end":"2026-09-17T21:33:49.610733Z","steps":["trace[1267188136] 'process raft request' (duration: 277.902855ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:49.61089Z","caller":"traceutil/trace.go:171","msg":"trace[22139466] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"278.668847ms","start":"2026-09-17T21:33:49.332208Z","end":"2026-09-17T21:33:49.610877Z","steps":["trace[22139466] 'process raft request' (duration: 277.810502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:33:49.611186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.507895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3df7a66e-9d4c-4481-b148-da6aa6811507\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-17T21:33:49.611222Z","caller":"traceutil/trace.go:171","msg":"trace[256072387] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3df7a66e-9d4c-4481-b148-da6aa6811507; range_end:; response_count:1; response_revision:5320; }","duration":"282.592088ms","start":"2026-09-17T21:33:49.328619Z","end":"2026-09-17T21:33:49.611211Z","steps":["trace[256072387] 'agreement among raft nodes before linearized reading' (duration: 282.430584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T21:33:49.612296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.352319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-17T21:33:49.61251Z","caller":"traceutil/trace.go:171","msg":"trace[1279268423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5321; }","duration":"236.589406ms","start":"2026-09-17T21:33:49.375862Z","end":"2026-09-17T21:33:49.612451Z","steps":["trace[1279268423] 'agreement among raft nodes before linearized reading' (duration: 236.247257ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:33:49.61257Z","caller":"traceutil/trace.go:171","msg":"trace[473102115] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"178.613625ms","start":"2026-09-17T21:33:49.433939Z","end":"2026-09-17T21:33:49.612553Z","steps":["trace[473102115] 'process raft request' (duration: 178.041329ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:37:33.382937Z","caller":"traceutil/trace.go:171","msg":"trace[1753313536] transaction","detail":"{read_only:false; response_revision:6930; number_of_response:1; }","duration":"109.387207ms","start":"2026-09-17T21:37:33.273513Z","end":"2026-09-17T21:37:33.382901Z","steps":["trace[1753313536] 'process raft request' (duration: 109.231013ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:37:49.747574Z","caller":"traceutil/trace.go:171","msg":"trace[541191352] transaction","detail":"{read_only:false; response_revision:7073; number_of_response:1; }","duration":"197.166974ms","start":"2026-09-17T21:37:49.550379Z","end":"2026-09-17T21:37:49.747546Z","steps":["trace[541191352] 'process raft request' (duration: 146.305212ms)","trace[541191352] 'compare' (duration: 50.578523ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T21:37:49.748314Z","caller":"traceutil/trace.go:171","msg":"trace[104928221] transaction","detail":"{read_only:false; response_revision:7074; number_of_response:1; }","duration":"196.10501ms","start":"2026-09-17T21:37:49.552196Z","end":"2026-09-17T21:37:49.748301Z","steps":["trace[104928221] 'process raft request' (duration: 195.282152ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:37:49.748113Z","caller":"traceutil/trace.go:171","msg":"trace[449453275] linearizableReadLoop","detail":"{readStateIndex:7334; appliedIndex:7332; }","duration":"124.430966ms","start":"2026-09-17T21:37:49.623669Z","end":"2026-09-17T21:37:49.7481Z","steps":["trace[449453275] 'read index received' (duration: 73.070168ms)","trace[449453275] 'applied index is now lower than readState.Index' (duration: 51.360048ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T21:37:49.748674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.983825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T21:37:49.748718Z","caller":"traceutil/trace.go:171","msg":"trace[437181083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7074; }","duration":"125.071949ms","start":"2026-09-17T21:37:49.623634Z","end":"2026-09-17T21:37:49.748706Z","steps":["trace[437181083] 'agreement among raft nodes before linearized reading' (duration: 124.995376ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T21:38:46.274821Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5289} {"level":"info","ts":"2026-09-17T21:38:46.372652Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5289,"took":"96.151462ms","hash":1626369803,"current-db-size-bytes":52490240,"current-db-size":"52 MB","current-db-size-in-use-bytes":19075072,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-17T21:38:46.372751Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1626369803,"revision":5289,"compact-revision":2855}