{"level":"warn","ts":"2026-08-12T17:28:32.673624Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T17:28:32.673711Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.78:2380","--initial-cluster=instance=https://199.19.213.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.78:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-12T17:28:32.67378Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T17:28:32.673799Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.78:2380"]} {"level":"info","ts":"2026-08-12T17:28:32.673823Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-12T17:28:32.676723Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.78:2379"]} {"level":"info","ts":"2026-08-12T17:28:32.676994Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.78:2380"],"listen-peer-urls":["https://199.19.213.78:2380"],"advertise-client-urls":["https://199.19.213.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.78:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-12T17:28:32.683291Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.418496ms"} {"level":"info","ts":"2026-08-12T17:28:32.691372Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"173f1df9994fe3b5","cluster-id":"f38512da1b70c9fc"} {"level":"info","ts":"2026-08-12T17:28:32.691436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T17:28:32.691455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became follower at term 0"} {"level":"info","ts":"2026-08-12T17:28:32.691473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 173f1df9994fe3b5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T17:28:32.691486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became follower at term 1"} {"level":"info","ts":"2026-08-12T17:28:32.691522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 switched to configuration voters=(1675090544261325749)"} {"level":"warn","ts":"2026-08-12T17:28:32.698811Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T17:28:32.700284Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T17:28:32.701577Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-12T17:28:32.704527Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"173f1df9994fe3b5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T17:28:32.705287Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-12T17:28:32.705512Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-12T17:28:32.705701Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-12T17:28:32.705456Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"173f1df9994fe3b5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T17:28:32.706511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 switched to configuration voters=(1675090544261325749)"} {"level":"info","ts":"2026-08-12T17:28:32.707028Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f38512da1b70c9fc","local-member-id":"173f1df9994fe3b5","added-peer-id":"173f1df9994fe3b5","added-peer-peer-urls":["https://199.19.213.78:2380"]} {"level":"info","ts":"2026-08-12T17:28:32.710817Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-12T17:28:32.710888Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.78:2380"} {"level":"info","ts":"2026-08-12T17:28:32.71094Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.78:2380"} {"level":"info","ts":"2026-08-12T17:28:32.711306Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"173f1df9994fe3b5","initial-advertise-peer-urls":["https://199.19.213.78:2380"],"listen-peer-urls":["https://199.19.213.78:2380"],"advertise-client-urls":["https://199.19.213.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T17:28:32.711369Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T17:28:33.592255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T17:28:33.592342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T17:28:33.59237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 received MsgPreVoteResp from 173f1df9994fe3b5 at term 1"} {"level":"info","ts":"2026-08-12T17:28:33.592394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became candidate at term 2"} {"level":"info","ts":"2026-08-12T17:28:33.59241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 received MsgVoteResp from 173f1df9994fe3b5 at term 2"} {"level":"info","ts":"2026-08-12T17:28:33.592439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became leader at term 2"} {"level":"info","ts":"2026-08-12T17:28:33.592467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 173f1df9994fe3b5 elected leader 173f1df9994fe3b5 at term 2"} {"level":"info","ts":"2026-08-12T17:28:33.629847Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T17:28:33.88982Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"173f1df9994fe3b5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.78:2379]}","request-path":"/0/members/173f1df9994fe3b5/attributes","cluster-id":"f38512da1b70c9fc","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T17:28:33.889886Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T17:28:33.889958Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T17:28:33.890271Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T17:28:33.89033Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T17:28:33.891905Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f38512da1b70c9fc","local-member-id":"173f1df9994fe3b5","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T17:28:33.892185Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T17:28:33.892268Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T17:28:33.896311Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.78:2379"} {"level":"info","ts":"2026-08-12T17:28:33.896848Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-12T17:28:36.711689Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196700680008661,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T17:28:36.831174Z","caller":"traceutil/trace.go:171","msg":"trace[481040907] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"620.442234ms","start":"2026-08-12T17:28:36.210706Z","end":"2026-08-12T17:28:36.831148Z","steps":["trace[481040907] 'read index received' (duration: 620.258981ms)","trace[481040907] 'applied index is now lower than readState.Index' (duration: 182.383µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:28:36.831311Z","caller":"traceutil/trace.go:171","msg":"trace[1537496117] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"621.096275ms","start":"2026-08-12T17:28:36.210206Z","end":"2026-08-12T17:28:36.831302Z","steps":["trace[1537496117] 'process raft request' (duration: 620.80832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:36.831774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:36.210184Z","time spent":"621.156236ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T17:28:36.831744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.914339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T17:28:36.831872Z","caller":"traceutil/trace.go:171","msg":"trace[1851745952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"521.121313ms","start":"2026-08-12T17:28:36.310724Z","end":"2026-08-12T17:28:36.831845Z","steps":["trace[1851745952] 'agreement among raft nodes before linearized reading' (duration: 520.818558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:36.831932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.185777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-12T17:28:36.83197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:36.310696Z","time spent":"521.263586ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T17:28:36.832036Z","caller":"traceutil/trace.go:171","msg":"trace[852518450] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"621.322049ms","start":"2026-08-12T17:28:36.21069Z","end":"2026-08-12T17:28:36.832012Z","steps":["trace[852518450] 'agreement among raft nodes before linearized reading' (duration: 621.129146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:36.832104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:36.210683Z","time spent":"621.41242ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"info","ts":"2026-08-12T17:28:36.979564Z","caller":"traceutil/trace.go:171","msg":"trace[286615836] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"128.891879ms","start":"2026-08-12T17:28:36.85064Z","end":"2026-08-12T17:28:36.979532Z","steps":["trace[286615836] 'process raft request' (duration: 87.767091ms)","trace[286615836] 'compare' (duration: 41.001066ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:28:39.982369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.404137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:28:39.983257Z","caller":"traceutil/trace.go:171","msg":"trace[1381544954] linearizableReadLoop","detail":"{readStateIndex:272; appliedIndex:271; }","duration":"1.081118458s","start":"2026-08-12T17:28:38.902115Z","end":"2026-08-12T17:28:39.983234Z","steps":["trace[1381544954] 'read index received' (duration: 485.45323ms)","trace[1381544954] 'applied index is now lower than readState.Index' (duration: 595.663428ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:28:39.983362Z","caller":"traceutil/trace.go:171","msg":"trace[248526515] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"1.120900289s","start":"2026-08-12T17:28:38.862446Z","end":"2026-08-12T17:28:39.983346Z","steps":["trace[248526515] 'process raft request' (duration: 525.106418ms)","trace[248526515] 'compare' (duration: 594.286245ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:28:39.983456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:38.862432Z","time spent":"1.12097523s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:28:39.983528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081399753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-12T17:28:39.983607Z","caller":"traceutil/trace.go:171","msg":"trace[138763702] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:266; }","duration":"1.081506224s","start":"2026-08-12T17:28:38.902081Z","end":"2026-08-12T17:28:39.983587Z","steps":["trace[138763702] 'agreement among raft nodes before linearized reading' (duration: 1.081377332s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:39.983651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:38.90207Z","time spent":"1.081572415s","remote":"127.0.0.1:39468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-12T17:28:40.015001Z","caller":"traceutil/trace.go:171","msg":"trace[639127231] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"1.047042091s","start":"2026-08-12T17:28:38.967937Z","end":"2026-08-12T17:28:40.01498Z","steps":["trace[639127231] 'process raft request' (duration: 1.04695282s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:40.015105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:38.967903Z","time spent":"1.047160363s","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T17:28:42.046435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162830795s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:28:42.046681Z","caller":"traceutil/trace.go:171","msg":"trace[1361907903] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"2.059278166s","start":"2026-08-12T17:28:39.987373Z","end":"2026-08-12T17:28:42.046651Z","steps":["trace[1361907903] 'process raft request' (duration: 2.059143303s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:42.046772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.086837647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:28:42.046761Z","caller":"traceutil/trace.go:171","msg":"trace[1888830236] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"2.31123133s","start":"2026-08-12T17:28:39.735489Z","end":"2026-08-12T17:28:42.04672Z","steps":["trace[1888830236] 'process raft request' (duration: 1.148052778s)","trace[1888830236] 'compare' (duration: 1.162712254s)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:28:42.046798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:39.987359Z","time spent":"2.059383749s","remote":"127.0.0.1:39578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:28:42.046832Z","caller":"traceutil/trace.go:171","msg":"trace[2051786184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:269; }","duration":"2.086922588s","start":"2026-08-12T17:28:39.959891Z","end":"2026-08-12T17:28:42.046814Z","steps":["trace[2051786184] 'agreement among raft nodes before linearized reading' (duration: 2.086760295s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:42.046878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:39.959875Z","time spent":"2.08699495s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T17:28:42.046901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:39.735447Z","time spent":"2.311390873s","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T17:28:42.04693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.851198767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-08-12T17:28:42.046995Z","caller":"traceutil/trace.go:171","msg":"trace[2078153142] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:269; }","duration":"2.8513107s","start":"2026-08-12T17:28:39.19567Z","end":"2026-08-12T17:28:42.04698Z","steps":["trace[2078153142] 'agreement among raft nodes before linearized reading' (duration: 2.851172557s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:42.047048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:39.195644Z","time spent":"2.851396861s","remote":"127.0.0.1:39542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3731,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-12T17:28:42.04658Z","caller":"traceutil/trace.go:171","msg":"trace[605774938] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:273; }","duration":"2.063259905s","start":"2026-08-12T17:28:39.983305Z","end":"2026-08-12T17:28:42.046565Z","steps":["trace[605774938] 'read index received' (duration: 31.65892ms)","trace[605774938] 'applied index is now lower than readState.Index' (duration: 2.031599885s)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:28:42.0486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.780267398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-12T17:28:42.04866Z","caller":"traceutil/trace.go:171","msg":"trace[1563019710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:269; }","duration":"1.780409061s","start":"2026-08-12T17:28:40.268234Z","end":"2026-08-12T17:28:42.048643Z","steps":["trace[1563019710] 'agreement among raft nodes before linearized reading' (duration: 1.780255619s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:42.048706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:40.268204Z","time spent":"1.780492472s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T17:28:42.048951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438603765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-12T17:28:42.04902Z","caller":"traceutil/trace.go:171","msg":"trace[1223721259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:269; }","duration":"1.438721387s","start":"2026-08-12T17:28:40.610282Z","end":"2026-08-12T17:28:42.049004Z","steps":["trace[1223721259] 'agreement among raft nodes before linearized reading' (duration: 1.438617495s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:42.049055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:40.610262Z","time spent":"1.438784948s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-12T17:28:42.049166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.123878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-08-12T17:28:42.049199Z","caller":"traceutil/trace.go:171","msg":"trace[789869636] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:269; }","duration":"468.193559ms","start":"2026-08-12T17:28:41.580997Z","end":"2026-08-12T17:28:42.04919Z","steps":["trace[789869636] 'agreement among raft nodes before linearized reading' (duration: 468.131048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:42.049225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:41.580975Z","time spent":"468.244ms","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5536,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-08-12T17:28:42.380973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.836372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:28:42.381223Z","caller":"traceutil/trace.go:171","msg":"trace[1447520052] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:275; }","duration":"324.538575ms","start":"2026-08-12T17:28:42.056668Z","end":"2026-08-12T17:28:42.381206Z","steps":["trace[1447520052] 'read index received' (duration: 20.776892ms)","trace[1447520052] 'applied index is now lower than readState.Index' (duration: 303.760733ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:28:42.381258Z","caller":"traceutil/trace.go:171","msg":"trace[2032227664] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"328.030663ms","start":"2026-08-12T17:28:42.053171Z","end":"2026-08-12T17:28:42.381202Z","steps":["trace[2032227664] 'process raft request' (duration: 131.886196ms)","trace[2032227664] 'compare' (duration: 195.69961ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:28:42.381357Z","caller":"traceutil/trace.go:171","msg":"trace[1290213808] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"327.333602ms","start":"2026-08-12T17:28:42.053988Z","end":"2026-08-12T17:28:42.381321Z","steps":["trace[1290213808] 'process raft request' (duration: 327.137769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:42.381458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:42.053148Z","time spent":"328.208967ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:28:42.381446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.737099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T17:28:42.381513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:42.053976Z","time spent":"327.459464ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:28:42.381544Z","caller":"traceutil/trace.go:171","msg":"trace[103086889] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:274; }","duration":"324.866621ms","start":"2026-08-12T17:28:42.056655Z","end":"2026-08-12T17:28:42.381522Z","steps":["trace[103086889] 'agreement among raft nodes before linearized reading' (duration: 324.618687ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:28:42.381525Z","caller":"traceutil/trace.go:171","msg":"trace[343505128] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"328.207936ms","start":"2026-08-12T17:28:42.05329Z","end":"2026-08-12T17:28:42.381498Z","steps":["trace[343505128] 'process raft request' (duration: 327.791259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:42.381626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:42.056615Z","time spent":"324.995302ms","remote":"127.0.0.1:39578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" "} {"level":"warn","ts":"2026-08-12T17:28:42.381675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:42.05328Z","time spent":"328.335479ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:28:42.381739Z","caller":"traceutil/trace.go:171","msg":"trace[873571223] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"326.02166ms","start":"2026-08-12T17:28:42.055695Z","end":"2026-08-12T17:28:42.381716Z","steps":["trace[873571223] 'process raft request' (duration: 325.469701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:42.381847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:42.055642Z","time spent":"326.157551ms","remote":"127.0.0.1:39542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:28:42.469749Z","caller":"traceutil/trace.go:171","msg":"trace[1609460689] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"387.505764ms","start":"2026-08-12T17:28:42.082225Z","end":"2026-08-12T17:28:42.469731Z","steps":["trace[1609460689] 'process raft request' (duration: 387.384862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:42.470023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:28:42.082208Z","time spent":"387.601885ms","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:28:42.596075Z","caller":"traceutil/trace.go:171","msg":"trace[280553743] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"209.291261ms","start":"2026-08-12T17:28:42.386737Z","end":"2026-08-12T17:28:42.596028Z","steps":["trace[280553743] 'process raft request' (duration: 146.108668ms)","trace[280553743] 'compare' (duration: 62.965079ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:28:42.596149Z","caller":"traceutil/trace.go:171","msg":"trace[669038287] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:281; }","duration":"126.407204ms","start":"2026-08-12T17:28:42.469705Z","end":"2026-08-12T17:28:42.596112Z","steps":["trace[669038287] 'read index received' (duration: 63.1087ms)","trace[669038287] 'applied index is now lower than readState.Index' (duration: 63.296164ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:28:42.596332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.757568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-f3c2fj\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T17:28:42.596386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.189166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-12T17:28:42.596529Z","caller":"traceutil/trace.go:171","msg":"trace[2064726721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:276; }","duration":"210.382829ms","start":"2026-08-12T17:28:42.386134Z","end":"2026-08-12T17:28:42.596517Z","steps":["trace[2064726721] 'agreement among raft nodes before linearized reading' (duration: 210.145595ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:28:42.596398Z","caller":"traceutil/trace.go:171","msg":"trace[1690916681] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-f3c2fj; range_end:; response_count:0; response_revision:276; }","duration":"209.87083ms","start":"2026-08-12T17:28:42.386512Z","end":"2026-08-12T17:28:42.596383Z","steps":["trace[1690916681] 'agreement among raft nodes before linearized reading' (duration: 209.674356ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:28:42.596851Z","caller":"traceutil/trace.go:171","msg":"trace[1674845641] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"123.357052ms","start":"2026-08-12T17:28:42.473477Z","end":"2026-08-12T17:28:42.596834Z","steps":["trace[1674845641] 'process raft request' (duration: 122.567079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:42.597185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.909132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-08-12T17:28:42.597283Z","caller":"traceutil/trace.go:171","msg":"trace[1095220771] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:277; }","duration":"124.145305ms","start":"2026-08-12T17:28:42.473116Z","end":"2026-08-12T17:28:42.597261Z","steps":["trace[1095220771] 'agreement among raft nodes before linearized reading' (duration: 123.722048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:28:48.18698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.203229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:28:48.187119Z","caller":"traceutil/trace.go:171","msg":"trace[1420073987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:402; }","duration":"265.393793ms","start":"2026-08-12T17:28:47.921681Z","end":"2026-08-12T17:28:48.187074Z","steps":["trace[1420073987] 'range keys from in-memory index tree' (duration: 265.072917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:03.255233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.978155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:29:03.255413Z","caller":"traceutil/trace.go:171","msg":"trace[1417896626] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"200.407773ms","start":"2026-08-12T17:29:03.054984Z","end":"2026-08-12T17:29:03.255392Z","steps":["trace[1417896626] 'process raft request' (duration: 98.201704ms)","trace[1417896626] 'compare' (duration: 101.870793ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:03.255412Z","caller":"traceutil/trace.go:171","msg":"trace[1136517976] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"197.642598ms","start":"2026-08-12T17:29:03.057757Z","end":"2026-08-12T17:29:03.2554Z","steps":["trace[1136517976] 'process raft request' (duration: 197.559406ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:03.255619Z","caller":"traceutil/trace.go:171","msg":"trace[1421535018] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"186.956094ms","start":"2026-08-12T17:29:03.068643Z","end":"2026-08-12T17:29:03.255599Z","steps":["trace[1421535018] 'process raft request' (duration: 186.798191ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:03.255649Z","caller":"traceutil/trace.go:171","msg":"trace[1143741910] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"184.462775ms","start":"2026-08-12T17:29:03.07118Z","end":"2026-08-12T17:29:03.255643Z","steps":["trace[1143741910] 'process raft request' (duration: 184.334292ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:03.255632Z","caller":"traceutil/trace.go:171","msg":"trace[238932276] transaction","detail":"{read_only:false; number_of_response:1; response_revision:460; }","duration":"184.408083ms","start":"2026-08-12T17:29:03.071204Z","end":"2026-08-12T17:29:03.255612Z","steps":["trace[238932276] 'process raft request' (duration: 184.346812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:03.506808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.68286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:29:03.50691Z","caller":"traceutil/trace.go:171","msg":"trace[1310305298] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"149.889023ms","start":"2026-08-12T17:29:03.357007Z","end":"2026-08-12T17:29:03.506896Z","steps":["trace[1310305298] 'process raft request' (duration: 48.05954ms)","trace[1310305298] 'compare' (duration: 101.553268ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:03.511478Z","caller":"traceutil/trace.go:171","msg":"trace[1288259880] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"152.73711ms","start":"2026-08-12T17:29:03.358728Z","end":"2026-08-12T17:29:03.511465Z","steps":["trace[1288259880] 'process raft request' (duration: 152.6993ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:03.511655Z","caller":"traceutil/trace.go:171","msg":"trace[1077075436] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"153.253788ms","start":"2026-08-12T17:29:03.358366Z","end":"2026-08-12T17:29:03.511619Z","steps":["trace[1077075436] 'process raft request' (duration: 152.958113ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:03.511704Z","caller":"traceutil/trace.go:171","msg":"trace[87297655] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"153.064994ms","start":"2026-08-12T17:29:03.358612Z","end":"2026-08-12T17:29:03.511677Z","steps":["trace[87297655] 'process raft request' (duration: 152.76738ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:03.511769Z","caller":"traceutil/trace.go:171","msg":"trace[1644351905] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"154.124762ms","start":"2026-08-12T17:29:03.357635Z","end":"2026-08-12T17:29:03.511759Z","steps":["trace[1644351905] 'process raft request' (duration: 153.629024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:03.970305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.364697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:29:03.970435Z","caller":"traceutil/trace.go:171","msg":"trace[159181629] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"210.526308ms","start":"2026-08-12T17:29:03.759888Z","end":"2026-08-12T17:29:03.970414Z","steps":["trace[159181629] 'read index received' (duration: 42.957687ms)","trace[159181629] 'applied index is now lower than readState.Index' (duration: 167.566811ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:03.970465Z","caller":"traceutil/trace.go:171","msg":"trace[2005165639] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"364.174872ms","start":"2026-08-12T17:29:03.606256Z","end":"2026-08-12T17:29:03.970431Z","steps":["trace[2005165639] 'process raft request' (duration: 196.585801ms)","trace[2005165639] 'compare' (duration: 167.239415ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:29:03.970523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.636159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T17:29:03.970561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:03.606229Z","time spent":"364.290013ms","remote":"127.0.0.1:39818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:29:03.970572Z","caller":"traceutil/trace.go:171","msg":"trace[1087093988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"210.719241ms","start":"2026-08-12T17:29:03.759839Z","end":"2026-08-12T17:29:03.970558Z","steps":["trace[1087093988] 'agreement among raft nodes before linearized reading' (duration: 210.64042ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:06.255075Z","caller":"traceutil/trace.go:171","msg":"trace[957032192] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"115.144259ms","start":"2026-08-12T17:29:06.139905Z","end":"2026-08-12T17:29:06.25505Z","steps":["trace[957032192] 'process raft request' (duration: 115.006037ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:07.366452Z","caller":"traceutil/trace.go:171","msg":"trace[511711063] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"489.353062ms","start":"2026-08-12T17:29:06.877075Z","end":"2026-08-12T17:29:07.366428Z","steps":["trace[511711063] 'process raft request' (duration: 489.21307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:07.366593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:06.877042Z","time spent":"489.475684ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:29:07.91332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.173975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.78\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-08-12T17:29:07.913346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.911286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:29:07.913417Z","caller":"traceutil/trace.go:171","msg":"trace[1671465809] range","detail":"{range_begin:/registry/masterleases/199.19.213.78; range_end:; response_count:1; response_revision:482; }","duration":"100.297587ms","start":"2026-08-12T17:29:07.813089Z","end":"2026-08-12T17:29:07.913387Z","steps":["trace[1671465809] 'range keys from in-memory index tree' (duration: 100.064754ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:07.913414Z","caller":"traceutil/trace.go:171","msg":"trace[1267212500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"150.017678ms","start":"2026-08-12T17:29:07.763376Z","end":"2026-08-12T17:29:07.913394Z","steps":["trace[1267212500] 'range keys from in-memory index tree' (duration: 149.820895ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:08.056991Z","caller":"traceutil/trace.go:171","msg":"trace[200512779] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"141.764364ms","start":"2026-08-12T17:29:07.915189Z","end":"2026-08-12T17:29:08.056953Z","steps":["trace[200512779] 'read index received' (duration: 141.5569ms)","trace[200512779] 'applied index is now lower than readState.Index' (duration: 206.194µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:29:08.057203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.999448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:29:08.057466Z","caller":"traceutil/trace.go:171","msg":"trace[1874221363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"142.278513ms","start":"2026-08-12T17:29:07.915174Z","end":"2026-08-12T17:29:08.057452Z","steps":["trace[1874221363] 'agreement among raft nodes before linearized reading' (duration: 141.971337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:08.343943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.509501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:29:08.344108Z","caller":"traceutil/trace.go:171","msg":"trace[6517511] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"285.100628ms","start":"2026-08-12T17:29:08.058946Z","end":"2026-08-12T17:29:08.344047Z","steps":["trace[6517511] 'process raft request' (duration: 139.405314ms)","trace[6517511] 'compare' (duration: 145.374749ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:08.58192Z","caller":"traceutil/trace.go:171","msg":"trace[1879906078] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"150.110796ms","start":"2026-08-12T17:29:08.431772Z","end":"2026-08-12T17:29:08.581883Z","steps":["trace[1879906078] 'process raft request' (duration: 127.924219ms)","trace[1879906078] 'compare' (duration: 21.897943ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:08.895065Z","caller":"traceutil/trace.go:171","msg":"trace[1226602283] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"218.688059ms","start":"2026-08-12T17:29:08.67635Z","end":"2026-08-12T17:29:08.895038Z","steps":["trace[1226602283] 'process raft request' (duration: 119.562613ms)","trace[1226602283] 'compare' (duration: 99.000924ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:13.513023Z","caller":"traceutil/trace.go:171","msg":"trace[749996584] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"122.265163ms","start":"2026-08-12T17:29:13.39073Z","end":"2026-08-12T17:29:13.512995Z","steps":["trace[749996584] 'process raft request' (duration: 121.981268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:13.826428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.481759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:29:13.826568Z","caller":"traceutil/trace.go:171","msg":"trace[1525713523] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"310.176692ms","start":"2026-08-12T17:29:13.516367Z","end":"2026-08-12T17:29:13.826544Z","steps":["trace[1525713523] 'process raft request' (duration: 22.455209ms)","trace[1525713523] 'compare' (duration: 287.187265ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:29:13.826665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:13.516347Z","time spent":"310.287143ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:29:15.133291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196700680009561,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T17:29:15.439833Z","caller":"traceutil/trace.go:171","msg":"trace[1276591851] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"806.864499ms","start":"2026-08-12T17:29:14.63294Z","end":"2026-08-12T17:29:15.439805Z","steps":["trace[1276591851] 'read index received' (duration: 806.593165ms)","trace[1276591851] 'applied index is now lower than readState.Index' (duration: 269.724µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:15.439874Z","caller":"traceutil/trace.go:171","msg":"trace[1824801807] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"810.425316ms","start":"2026-08-12T17:29:14.629416Z","end":"2026-08-12T17:29:15.439841Z","steps":["trace[1824801807] 'process raft request' (duration: 810.231053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:15.44002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.051102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-12T17:29:15.440072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:14.629388Z","time spent":"810.564478ms","remote":"127.0.0.1:39650","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-08-12T17:29:15.440091Z","caller":"traceutil/trace.go:171","msg":"trace[1991280733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"807.170475ms","start":"2026-08-12T17:29:14.632905Z","end":"2026-08-12T17:29:15.440075Z","steps":["trace[1991280733] 'agreement among raft nodes before linearized reading' (duration: 807.047313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:15.440078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.738323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T17:29:15.440158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:14.632889Z","time spent":"807.242745ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-12T17:29:15.440164Z","caller":"traceutil/trace.go:171","msg":"trace[1592400067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"679.865876ms","start":"2026-08-12T17:29:14.76027Z","end":"2026-08-12T17:29:15.440135Z","steps":["trace[1592400067] 'agreement among raft nodes before linearized reading' (duration: 679.730363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:15.440212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:14.760236Z","time spent":"679.962568ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T17:29:15.440443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.69932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-12T17:29:15.440542Z","caller":"traceutil/trace.go:171","msg":"trace[1923295611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:497; }","duration":"512.871744ms","start":"2026-08-12T17:29:14.927646Z","end":"2026-08-12T17:29:15.440517Z","steps":["trace[1923295611] 'agreement among raft nodes before linearized reading' (duration: 512.613079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:15.440638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:14.927622Z","time spent":"513.001315ms","remote":"127.0.0.1:39650","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":"info","ts":"2026-08-12T17:29:15.711073Z","caller":"traceutil/trace.go:171","msg":"trace[998703372] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"265.874181ms","start":"2026-08-12T17:29:15.445173Z","end":"2026-08-12T17:29:15.711047Z","steps":["trace[998703372] 'process raft request' (duration: 263.028216ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:15.711268Z","caller":"traceutil/trace.go:171","msg":"trace[752602154] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:516; }","duration":"242.622672ms","start":"2026-08-12T17:29:15.468619Z","end":"2026-08-12T17:29:15.711241Z","steps":["trace[752602154] 'read index received' (duration: 239.721916ms)","trace[752602154] 'applied index is now lower than readState.Index' (duration: 2.899246ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:15.711366Z","caller":"traceutil/trace.go:171","msg":"trace[1026221846] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"265.16686ms","start":"2026-08-12T17:29:15.446161Z","end":"2026-08-12T17:29:15.711328Z","steps":["trace[1026221846] 'process raft request' (duration: 264.803805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:15.711535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.894306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-08-12T17:29:15.711578Z","caller":"traceutil/trace.go:171","msg":"trace[690708280] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:499; }","duration":"243.028628ms","start":"2026-08-12T17:29:15.468535Z","end":"2026-08-12T17:29:15.711563Z","steps":["trace[690708280] 'agreement among raft nodes before linearized reading' (duration: 242.777484ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:17.929406Z","caller":"traceutil/trace.go:171","msg":"trace[286277274] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:555; }","duration":"169.388708ms","start":"2026-08-12T17:29:17.759993Z","end":"2026-08-12T17:29:17.929382Z","steps":["trace[286277274] 'read index received' (duration: 33.029184ms)","trace[286277274] 'applied index is now lower than readState.Index' (duration: 136.358584ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:17.929715Z","caller":"traceutil/trace.go:171","msg":"trace[1713532287] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"211.08176ms","start":"2026-08-12T17:29:17.718622Z","end":"2026-08-12T17:29:17.929704Z","steps":["trace[1713532287] 'process raft request' (duration: 158.284082ms)","trace[1713532287] 'compare' (duration: 52.342221ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:29:17.930008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.297586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-12T17:29:17.930116Z","caller":"traceutil/trace.go:171","msg":"trace[1895818971] range","detail":"{range_begin:/registry/masterleases/199.19.213.78; range_end:; response_count:1; response_revision:538; }","duration":"116.446738ms","start":"2026-08-12T17:29:17.813646Z","end":"2026-08-12T17:29:17.930093Z","steps":["trace[1895818971] 'agreement among raft nodes before linearized reading' (duration: 116.232805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:17.930046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.034918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:29:17.930266Z","caller":"traceutil/trace.go:171","msg":"trace[1271585761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"170.284262ms","start":"2026-08-12T17:29:17.759957Z","end":"2026-08-12T17:29:17.930241Z","steps":["trace[1271585761] 'agreement among raft nodes before linearized reading' (duration: 170.044139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:18.542411Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196700680009640,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T17:29:18.723785Z","caller":"traceutil/trace.go:171","msg":"trace[1042834592] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:557; }","duration":"682.490322ms","start":"2026-08-12T17:29:18.041279Z","end":"2026-08-12T17:29:18.723769Z","steps":["trace[1042834592] 'read index received' (duration: 638.816971ms)","trace[1042834592] 'applied index is now lower than readState.Index' (duration: 43.672561ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:29:18.723934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:17.931403Z","time spent":"792.525417ms","remote":"127.0.0.1:39404","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-12T17:29:18.724089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.772676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:29:18.724655Z","caller":"traceutil/trace.go:171","msg":"trace[672742730] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:539; }","duration":"683.398485ms","start":"2026-08-12T17:29:18.041241Z","end":"2026-08-12T17:29:18.72464Z","steps":["trace[672742730] 'agreement among raft nodes before linearized reading' (duration: 682.747845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:18.724711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:18.041224Z","time spent":"683.463166ms","remote":"127.0.0.1:39436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-08-12T17:29:18.723899Z","caller":"traceutil/trace.go:171","msg":"trace[1895260318] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"789.464369ms","start":"2026-08-12T17:29:17.934422Z","end":"2026-08-12T17:29:18.723886Z","steps":["trace[1895260318] 'process raft request' (duration: 789.262126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:18.724923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:17.934407Z","time spent":"790.414774ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:29:19.404186Z","caller":"traceutil/trace.go:171","msg":"trace[2052319619] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"481.130013ms","start":"2026-08-12T17:29:18.92303Z","end":"2026-08-12T17:29:19.40416Z","steps":["trace[2052319619] 'process raft request' (duration: 480.92031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:19.40431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:18.92301Z","time spent":"481.240245ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:29:19.564168Z","caller":"traceutil/trace.go:171","msg":"trace[709446056] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"635.584057ms","start":"2026-08-12T17:29:18.928528Z","end":"2026-08-12T17:29:19.564112Z","steps":["trace[709446056] 'process raft request' (duration: 635.197181ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:19.564451Z","caller":"traceutil/trace.go:171","msg":"trace[172706450] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"611.986782ms","start":"2026-08-12T17:29:18.952417Z","end":"2026-08-12T17:29:19.564404Z","steps":["trace[172706450] 'process raft request' (duration: 611.682348ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:19.564631Z","caller":"traceutil/trace.go:171","msg":"trace[1284064365] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"610.291786ms","start":"2026-08-12T17:29:18.954332Z","end":"2026-08-12T17:29:19.564623Z","steps":["trace[1284064365] 'process raft request' (duration: 609.817588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:19.564828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:18.952408Z","time spent":"612.254916ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:29:19.564894Z","caller":"traceutil/trace.go:171","msg":"trace[466573501] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"472.9426ms","start":"2026-08-12T17:29:19.091929Z","end":"2026-08-12T17:29:19.564872Z","steps":["trace[466573501] 'process raft request' (duration: 472.685637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:19.564934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:18.954325Z","time spent":"610.469639ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:29:19.564937Z","caller":"traceutil/trace.go:171","msg":"trace[1923260921] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"615.731092ms","start":"2026-08-12T17:29:18.949166Z","end":"2026-08-12T17:29:19.564897Z","steps":["trace[1923260921] 'process raft request' (duration: 614.881979ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:19.564964Z","caller":"traceutil/trace.go:171","msg":"trace[1979742433] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"596.371854ms","start":"2026-08-12T17:29:18.968542Z","end":"2026-08-12T17:29:19.564914Z","steps":["trace[1979742433] 'process raft request' (duration: 595.757035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:19.565033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:19.091899Z","time spent":"473.072303ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T17:29:19.565049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:18.949146Z","time spent":"615.858814ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T17:29:19.565069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:18.968534Z","time spent":"596.487456ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:29:19.565169Z","caller":"traceutil/trace.go:171","msg":"trace[801721598] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"579.126493ms","start":"2026-08-12T17:29:18.98603Z","end":"2026-08-12T17:29:19.565157Z","steps":["trace[801721598] 'process raft request' (duration: 578.33283ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:19.565179Z","caller":"traceutil/trace.go:171","msg":"trace[262910297] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"570.383794ms","start":"2026-08-12T17:29:18.994783Z","end":"2026-08-12T17:29:19.565167Z","steps":["trace[262910297] 'process raft request' (duration: 569.639832ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:19.565172Z","caller":"traceutil/trace.go:171","msg":"trace[1689429709] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"606.309633ms","start":"2026-08-12T17:29:18.958848Z","end":"2026-08-12T17:29:19.565157Z","steps":["trace[1689429709] 'process raft request' (duration: 605.365097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:19.565225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:18.986016Z","time spent":"579.184284ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T17:29:19.565228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:18.994748Z","time spent":"570.457066ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T17:29:19.565243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:18.958837Z","time spent":"606.374785ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:29:19.564487Z","caller":"traceutil/trace.go:171","msg":"trace[1888900319] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"631.4106ms","start":"2026-08-12T17:29:18.933042Z","end":"2026-08-12T17:29:19.564452Z","steps":["trace[1888900319] 'process raft request' (duration: 630.951683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:19.565316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:18.933026Z","time spent":"632.263685ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T17:29:19.565185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:18.928508Z","time spent":"635.773089ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:29:19.564599Z","caller":"traceutil/trace.go:171","msg":"trace[2140251593] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"598.160463ms","start":"2026-08-12T17:29:18.966428Z","end":"2026-08-12T17:29:19.564588Z","steps":["trace[2140251593] 'process raft request' (duration: 597.829858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:19.565586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:18.966415Z","time spent":"599.145279ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:29:19.586861Z","caller":"traceutil/trace.go:171","msg":"trace[1248450291] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:561; }","duration":"132.074329ms","start":"2026-08-12T17:29:19.454773Z","end":"2026-08-12T17:29:19.586847Z","steps":["trace[1248450291] 'read index received' (duration: 109.047595ms)","trace[1248450291] 'applied index is now lower than readState.Index' (duration: 23.025714ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:29:19.586961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.184151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T17:29:19.586982Z","caller":"traceutil/trace.go:171","msg":"trace[1354817716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"132.275102ms","start":"2026-08-12T17:29:19.454703Z","end":"2026-08-12T17:29:19.586978Z","steps":["trace[1354817716] 'agreement among raft nodes before linearized reading' (duration: 132.202521ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:19.587106Z","caller":"traceutil/trace.go:171","msg":"trace[53904615] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"176.692565ms","start":"2026-08-12T17:29:19.410408Z","end":"2026-08-12T17:29:19.587101Z","steps":["trace[53904615] 'process raft request' (duration: 176.121866ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:19.587153Z","caller":"traceutil/trace.go:171","msg":"trace[2094510561] transaction","detail":"{read_only:false; number_of_response:1; response_revision:554; }","duration":"174.928987ms","start":"2026-08-12T17:29:19.41222Z","end":"2026-08-12T17:29:19.587149Z","steps":["trace[2094510561] 'process raft request' (duration: 174.599922ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:19.587215Z","caller":"traceutil/trace.go:171","msg":"trace[1958026702] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"177.224293ms","start":"2026-08-12T17:29:19.40995Z","end":"2026-08-12T17:29:19.587175Z","steps":["trace[1958026702] 'process raft request' (duration: 176.492452ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:19.73043Z","caller":"traceutil/trace.go:171","msg":"trace[1340909088] transaction","detail":"{read_only:false; number_of_response:0; response_revision:566; }","duration":"100.465649ms","start":"2026-08-12T17:29:19.629907Z","end":"2026-08-12T17:29:19.730373Z","steps":["trace[1340909088] 'process raft request' (duration: 99.672736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:25.169301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.376663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:29:25.169375Z","caller":"traceutil/trace.go:171","msg":"trace[145497530] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"294.872489ms","start":"2026-08-12T17:29:24.874491Z","end":"2026-08-12T17:29:25.169364Z","steps":["trace[145497530] 'process raft request' (duration: 133.387985ms)","trace[145497530] 'compare' (duration: 161.17805ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:25.907295Z","caller":"traceutil/trace.go:171","msg":"trace[1411919273] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"205.966171ms","start":"2026-08-12T17:29:25.701303Z","end":"2026-08-12T17:29:25.907269Z","steps":["trace[1411919273] 'read index received' (duration: 205.073728ms)","trace[1411919273] 'applied index is now lower than readState.Index' (duration: 891.433µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:25.907382Z","caller":"traceutil/trace.go:171","msg":"trace[1520253153] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"337.349013ms","start":"2026-08-12T17:29:25.570009Z","end":"2026-08-12T17:29:25.907358Z","steps":["trace[1520253153] 'process raft request' (duration: 336.653342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:25.907512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:25.569983Z","time spent":"337.450245ms","remote":"127.0.0.1:39436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:29:25.907525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.195596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T17:29:25.907559Z","caller":"traceutil/trace.go:171","msg":"trace[224305782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"206.294848ms","start":"2026-08-12T17:29:25.701254Z","end":"2026-08-12T17:29:25.907549Z","steps":["trace[224305782] 'agreement among raft nodes before linearized reading' (duration: 206.154056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:25.907709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.202959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:29:25.907824Z","caller":"traceutil/trace.go:171","msg":"trace[1830124177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"145.363011ms","start":"2026-08-12T17:29:25.762433Z","end":"2026-08-12T17:29:25.907796Z","steps":["trace[1830124177] 'agreement among raft nodes before linearized reading' (duration: 145.180538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:26.259306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.346949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:29:26.259517Z","caller":"traceutil/trace.go:171","msg":"trace[1815569066] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"346.302998ms","start":"2026-08-12T17:29:25.913141Z","end":"2026-08-12T17:29:26.259444Z","steps":["trace[1815569066] 'process raft request' (duration: 175.700355ms)","trace[1815569066] 'compare' (duration: 169.671739ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:29:26.259628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:25.913127Z","time spent":"346.45438ms","remote":"127.0.0.1:39650","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-08-12T17:29:26.282286Z","caller":"traceutil/trace.go:171","msg":"trace[1109273879] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"298.01831ms","start":"2026-08-12T17:29:25.984253Z","end":"2026-08-12T17:29:26.282272Z","steps":["trace[1109273879] 'read index received' (duration: 104.576708ms)","trace[1109273879] 'applied index is now lower than readState.Index' (duration: 193.440762ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:26.282376Z","caller":"traceutil/trace.go:171","msg":"trace[622688831] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"310.955133ms","start":"2026-08-12T17:29:25.97138Z","end":"2026-08-12T17:29:26.282335Z","steps":["trace[622688831] 'process raft request' (duration: 310.760701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:26.282477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.231582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-12T17:29:26.282513Z","caller":"traceutil/trace.go:171","msg":"trace[614081035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"298.270243ms","start":"2026-08-12T17:29:25.984233Z","end":"2026-08-12T17:29:26.282503Z","steps":["trace[614081035] 'agreement among raft nodes before linearized reading' (duration: 298.0959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:26.282542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:25.971354Z","time spent":"311.103785ms","remote":"127.0.0.1:39728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T17:29:27.586195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.571462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q9zrv\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-12T17:29:27.586291Z","caller":"traceutil/trace.go:171","msg":"trace[2135749049] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q9zrv; range_end:; response_count:1; response_revision:652; }","duration":"369.741925ms","start":"2026-08-12T17:29:27.216529Z","end":"2026-08-12T17:29:27.586271Z","steps":["trace[2135749049] 'range keys from in-memory index tree' (duration: 369.454711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:27.586349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:27.216506Z","time spent":"369.830177ms","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-q9zrv\" "} {"level":"warn","ts":"2026-08-12T17:29:28.260426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196700680009837,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T17:29:28.270573Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.032928049s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T17:29:28.271765Z","caller":"traceutil/trace.go:171","msg":"trace[1527235898] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.035896995s","start":"2026-08-12T17:29:27.235839Z","end":"2026-08-12T17:29:28.271736Z","steps":["trace[1527235898] 'process raft request' (duration: 1.035210363s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:28.271985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:27.235814Z","time spent":"1.036036838s","remote":"127.0.0.1:39436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:29:29.376824Z","caller":"traceutil/trace.go:171","msg":"trace[2079344519] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"1.616771746s","start":"2026-08-12T17:29:27.76003Z","end":"2026-08-12T17:29:29.376802Z","steps":["trace[2079344519] 'read index received' (duration: 510.809168ms)","trace[2079344519] 'applied index is now lower than readState.Index' (duration: 1.105952598s)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:29.376893Z","caller":"traceutil/trace.go:171","msg":"trace[1440497390] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.773561389s","start":"2026-08-12T17:29:27.603306Z","end":"2026-08-12T17:29:29.376868Z","steps":["trace[1440497390] 'process raft request' (duration: 1.773327345s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:29.377002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.616961048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:29:29.377049Z","caller":"traceutil/trace.go:171","msg":"trace[162632972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"1.617053049s","start":"2026-08-12T17:29:27.75998Z","end":"2026-08-12T17:29:29.377033Z","steps":["trace[162632972] 'agreement among raft nodes before linearized reading' (duration: 1.616955508s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:29.377051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:27.603271Z","time spent":"1.773699041s","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:29:29.377085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:27.759955Z","time spent":"1.617121861s","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T17:29:29.688819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.838107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:29:29.688934Z","caller":"traceutil/trace.go:171","msg":"trace[93075184] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"312.02984ms","start":"2026-08-12T17:29:29.376888Z","end":"2026-08-12T17:29:29.688918Z","steps":["trace[93075184] 'read index received' (duration: 9.044741ms)","trace[93075184] 'applied index is now lower than readState.Index' (duration: 302.983369ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:29.689015Z","caller":"traceutil/trace.go:171","msg":"trace[993849190] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.352151019s","start":"2026-08-12T17:29:28.336854Z","end":"2026-08-12T17:29:29.689006Z","steps":["trace[993849190] 'process raft request' (duration: 1.049046838s)","trace[993849190] 'compare' (duration: 302.650834ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:29:29.689062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:28.336819Z","time spent":"1.352222519s","remote":"127.0.0.1:39616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T17:29:29.689185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720205812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-12T17:29:29.689248Z","caller":"traceutil/trace.go:171","msg":"trace[2077172576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"1.720314195s","start":"2026-08-12T17:29:27.968918Z","end":"2026-08-12T17:29:29.689233Z","steps":["trace[2077172576] 'agreement among raft nodes before linearized reading' (duration: 1.720098471s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:29.689285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:27.968899Z","time spent":"1.720378746s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T17:29:29.689305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.874941714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-12T17:29:29.689341Z","caller":"traceutil/trace.go:171","msg":"trace[1774223875] range","detail":"{range_begin:/registry/masterleases/199.19.213.78; range_end:; response_count:1; response_revision:655; }","duration":"1.875010846s","start":"2026-08-12T17:29:27.814318Z","end":"2026-08-12T17:29:29.689329Z","steps":["trace[1774223875] 'agreement among raft nodes before linearized reading' (duration: 1.874949145s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:29.689361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:27.814295Z","time spent":"1.875061646s","remote":"127.0.0.1:39404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.78\" "} {"level":"warn","ts":"2026-08-12T17:29:29.689444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364807217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-12T17:29:29.689479Z","caller":"traceutil/trace.go:171","msg":"trace[578044077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"1.364844698s","start":"2026-08-12T17:29:28.324624Z","end":"2026-08-12T17:29:29.689468Z","steps":["trace[578044077] 'agreement among raft nodes before linearized reading' (duration: 1.364717236s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:29.689476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.6448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-12T17:29:29.68951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:28.324539Z","time spent":"1.36496447s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-12T17:29:29.689532Z","caller":"traceutil/trace.go:171","msg":"trace[1197039729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"894.73014ms","start":"2026-08-12T17:29:28.794782Z","end":"2026-08-12T17:29:29.689512Z","steps":["trace[1197039729] 'agreement among raft nodes before linearized reading' (duration: 894.579168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:29.689571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:28.794762Z","time spent":"894.800531ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T17:29:29.689567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426040634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T17:29:29.689643Z","caller":"traceutil/trace.go:171","msg":"trace[382799774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"1.426161705s","start":"2026-08-12T17:29:28.263459Z","end":"2026-08-12T17:29:29.68962Z","steps":["trace[382799774] 'agreement among raft nodes before linearized reading' (duration: 1.425913741s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:29.689267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.061482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:29:29.689792Z","caller":"traceutil/trace.go:171","msg":"trace[1961170471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"305.655721ms","start":"2026-08-12T17:29:29.384125Z","end":"2026-08-12T17:29:29.689781Z","steps":["trace[1961170471] 'agreement among raft nodes before linearized reading' (duration: 305.049681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:29.689825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:29.384108Z","time spent":"305.708722ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T17:29:29.689746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:28.263436Z","time spent":"1.426300298s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-12T17:29:30.191469Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196700680009843,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T17:29:30.692446Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196700680009843,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T17:29:31.193171Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196700680009843,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T17:29:31.691628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000877416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-12T17:29:31.691714Z","caller":"traceutil/trace.go:171","msg":"trace[111484874] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001019909s","start":"2026-08-12T17:29:29.690677Z","end":"2026-08-12T17:29:31.691697Z","steps":["trace[111484874] 'agreement among raft nodes before linearized reading' (duration: 2.000911957s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:31.691762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:29.690655Z","time spent":"2.001095259s","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T17:29:31.694382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196700680009843,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T17:29:31.752004Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.061217656s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-12T17:29:31.994046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.723803ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-08-12T17:29:31.99414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:29.690649Z","time spent":"2.303487788s","remote":"127.0.0.1:39404","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-12T17:29:31.996506Z","caller":"traceutil/trace.go:171","msg":"trace[1220534992] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"2.301746092s","start":"2026-08-12T17:29:29.694721Z","end":"2026-08-12T17:29:31.996467Z","steps":["trace[1220534992] 'process raft request' (duration: 2.301358486s)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:31.996555Z","caller":"traceutil/trace.go:171","msg":"trace[978763025] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"2.301790782s","start":"2026-08-12T17:29:29.694719Z","end":"2026-08-12T17:29:31.99651Z","steps":["trace[978763025] 'process raft request' (duration: 2.301575469s)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:31.9967Z","caller":"traceutil/trace.go:171","msg":"trace[1360274615] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"2.298286207s","start":"2026-08-12T17:29:29.698401Z","end":"2026-08-12T17:29:31.996687Z","steps":["trace[1360274615] 'process raft request' (duration: 2.298022353s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:31.996789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:29.698386Z","time spent":"2.298352888s","remote":"127.0.0.1:39650","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-08-12T17:29:31.996762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:29.694704Z","time spent":"2.301932184s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:29:31.996812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:29.694691Z","time spent":"2.301982416s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:29:31.996875Z","caller":"traceutil/trace.go:171","msg":"trace[1313736212] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:680; }","duration":"2.306125379s","start":"2026-08-12T17:29:29.690723Z","end":"2026-08-12T17:29:31.996848Z","steps":["trace[1313736212] 'read index received' (duration: 2.061595082s)","trace[1313736212] 'applied index is now lower than readState.Index' (duration: 244.528217ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:31.996971Z","caller":"traceutil/trace.go:171","msg":"trace[275622952] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"301.21199ms","start":"2026-08-12T17:29:31.695747Z","end":"2026-08-12T17:29:31.996959Z","steps":["trace[275622952] 'process raft request' (duration: 300.876185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:31.997036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:31.695714Z","time spent":"301.293501ms","remote":"127.0.0.1:39430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:29:31.996527Z","caller":"traceutil/trace.go:171","msg":"trace[954672596] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"2.299630749s","start":"2026-08-12T17:29:29.696838Z","end":"2026-08-12T17:29:31.996468Z","steps":["trace[954672596] 'process raft request' (duration: 2.299539957s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:31.997605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:29.696822Z","time spent":"2.300672144s","remote":"127.0.0.1:39650","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-08-12T17:29:31.999279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.306519766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-12T17:29:31.999336Z","caller":"traceutil/trace.go:171","msg":"trace[423003302] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:661; }","duration":"2.306592707s","start":"2026-08-12T17:29:29.692726Z","end":"2026-08-12T17:29:31.999319Z","steps":["trace[423003302] 'agreement among raft nodes before linearized reading' (duration: 2.306409994s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:31.999374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:29.692688Z","time spent":"2.306678347s","remote":"127.0.0.1:39578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-08-12T17:29:31.999266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.052542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:29:31.999469Z","caller":"traceutil/trace.go:171","msg":"trace[542550465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"300.284255ms","start":"2026-08-12T17:29:31.699169Z","end":"2026-08-12T17:29:31.999453Z","steps":["trace[542550465] 'agreement among raft nodes before linearized reading' (duration: 300.015951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:31.999545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:31.699156Z","time spent":"300.377517ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T17:29:33.920348Z","caller":"traceutil/trace.go:171","msg":"trace[1536085071] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"125.699063ms","start":"2026-08-12T17:29:33.79462Z","end":"2026-08-12T17:29:33.920319Z","steps":["trace[1536085071] 'process raft request' (duration: 125.466729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:36.607476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.835789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-08-12T17:29:36.607648Z","caller":"traceutil/trace.go:171","msg":"trace[11579086] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:733; }","duration":"181.064482ms","start":"2026-08-12T17:29:36.426552Z","end":"2026-08-12T17:29:36.607617Z","steps":["trace[11579086] 'range keys from in-memory index tree' (duration: 180.636596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:46.816486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196700680010230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T17:29:47.317433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196700680010230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T17:29:47.691418Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.393793712s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T17:29:47.691938Z","caller":"traceutil/trace.go:171","msg":"trace[1908160904] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"1.394369671s","start":"2026-08-12T17:29:46.297537Z","end":"2026-08-12T17:29:47.691906Z","steps":["trace[1908160904] 'process raft request' (duration: 1.394189639s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:47.692136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:46.297514Z","time spent":"1.394534363s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:29:47.762697Z","caller":"traceutil/trace.go:171","msg":"trace[490278758] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:846; }","duration":"1.446407156s","start":"2026-08-12T17:29:46.316267Z","end":"2026-08-12T17:29:47.762674Z","steps":["trace[490278758] 'read index received' (duration: 1.375461141s)","trace[490278758] 'applied index is now lower than readState.Index' (duration: 70.945205ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:29:47.763047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446761822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qlp6q\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-08-12T17:29:47.763115Z","caller":"traceutil/trace.go:171","msg":"trace[103583190] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qlp6q; range_end:; response_count:1; response_revision:818; }","duration":"1.446867433s","start":"2026-08-12T17:29:46.316229Z","end":"2026-08-12T17:29:47.763097Z","steps":["trace[103583190] 'agreement among raft nodes before linearized reading' (duration: 1.44665417s)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:47.763087Z","caller":"traceutil/trace.go:171","msg":"trace[862164627] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"600.279387ms","start":"2026-08-12T17:29:47.162768Z","end":"2026-08-12T17:29:47.763047Z","steps":["trace[862164627] 'process raft request' (duration: 599.792199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:47.763159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:46.316215Z","time spent":"1.446934524s","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qlp6q\" "} {"level":"warn","ts":"2026-08-12T17:29:47.763257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235005627s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-12T17:29:47.763306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:47.162736Z","time spent":"600.45856ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:29:47.763336Z","caller":"traceutil/trace.go:171","msg":"trace[1007959595] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:818; }","duration":"1.23513694s","start":"2026-08-12T17:29:46.528182Z","end":"2026-08-12T17:29:47.763319Z","steps":["trace[1007959595] 'agreement among raft nodes before linearized reading' (duration: 1.234978397s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:47.763384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:46.528159Z","time spent":"1.235214321s","remote":"127.0.0.1:39654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-08-12T17:29:47.763401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003216718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:29:47.763453Z","caller":"traceutil/trace.go:171","msg":"trace[1204955074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"1.003306948s","start":"2026-08-12T17:29:46.760131Z","end":"2026-08-12T17:29:47.763438Z","steps":["trace[1204955074] 'agreement among raft nodes before linearized reading' (duration: 1.003233837s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:47.763499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:46.760103Z","time spent":"1.00338419s","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T17:29:47.763542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.900879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T17:29:47.763707Z","caller":"traceutil/trace.go:171","msg":"trace[309774121] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:818; }","duration":"946.154984ms","start":"2026-08-12T17:29:46.817512Z","end":"2026-08-12T17:29:47.763667Z","steps":["trace[309774121] 'agreement among raft nodes before linearized reading' (duration: 945.810398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:47.763817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:46.817494Z","time spent":"946.303266ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-12T17:29:59.057702Z","caller":"traceutil/trace.go:171","msg":"trace[1579329719] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"105.967925ms","start":"2026-08-12T17:29:58.951706Z","end":"2026-08-12T17:29:59.057674Z","steps":["trace[1579329719] 'process raft request' (duration: 41.880035ms)","trace[1579329719] 'compare' (duration: 63.730345ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:59.101913Z","caller":"traceutil/trace.go:171","msg":"trace[1394367643] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"137.388182ms","start":"2026-08-12T17:29:58.964504Z","end":"2026-08-12T17:29:59.101892Z","steps":["trace[1394367643] 'process raft request' (duration: 137.328321ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:59.101976Z","caller":"traceutil/trace.go:171","msg":"trace[654329711] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"137.885559ms","start":"2026-08-12T17:29:58.964027Z","end":"2026-08-12T17:29:59.101913Z","steps":["trace[654329711] 'process raft request' (duration: 137.682336ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:59.280301Z","caller":"traceutil/trace.go:171","msg":"trace[1069123096] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"172.936739ms","start":"2026-08-12T17:29:59.107343Z","end":"2026-08-12T17:29:59.280279Z","steps":["trace[1069123096] 'read index received' (duration: 162.594322ms)","trace[1069123096] 'applied index is now lower than readState.Index' (duration: 10.341287ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:59.280454Z","caller":"traceutil/trace.go:171","msg":"trace[907284291] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"218.507759ms","start":"2026-08-12T17:29:59.061883Z","end":"2026-08-12T17:29:59.280391Z","steps":["trace[907284291] 'process raft request' (duration: 208.136652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:59.28051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.152392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3386"} {"level":"warn","ts":"2026-08-12T17:29:59.28069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.340535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-08-12T17:29:59.280783Z","caller":"traceutil/trace.go:171","msg":"trace[283030182] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:956; }","duration":"167.483727ms","start":"2026-08-12T17:29:59.11328Z","end":"2026-08-12T17:29:59.280764Z","steps":["trace[283030182] 'agreement among raft nodes before linearized reading' (duration: 167.180962ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:59.280707Z","caller":"traceutil/trace.go:171","msg":"trace[986254049] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:956; }","duration":"173.362796ms","start":"2026-08-12T17:29:59.107301Z","end":"2026-08-12T17:29:59.280664Z","steps":["trace[986254049] 'agreement among raft nodes before linearized reading' (duration: 173.086042ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:59.485465Z","caller":"traceutil/trace.go:171","msg":"trace[714471329] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:991; }","duration":"151.397802ms","start":"2026-08-12T17:29:59.334041Z","end":"2026-08-12T17:29:59.485439Z","steps":["trace[714471329] 'read index received' (duration: 27.355654ms)","trace[714471329] 'applied index is now lower than readState.Index' (duration: 124.041018ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:59.485516Z","caller":"traceutil/trace.go:171","msg":"trace[2056260404] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"151.803589ms","start":"2026-08-12T17:29:59.333675Z","end":"2026-08-12T17:29:59.485478Z","steps":["trace[2056260404] 'process raft request' (duration: 89.186591ms)","trace[2056260404] 'compare' (duration: 62.424505ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:29:59.485727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.661107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-d87cz\" ","response":"range_response_count:1 size:2650"} {"level":"info","ts":"2026-08-12T17:29:59.485776Z","caller":"traceutil/trace.go:171","msg":"trace[320733269] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-d87cz; range_end:; response_count:1; response_revision:961; }","duration":"151.758088ms","start":"2026-08-12T17:29:59.334007Z","end":"2026-08-12T17:29:59.485765Z","steps":["trace[320733269] 'agreement among raft nodes before linearized reading' (duration: 151.555925ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:59.566841Z","caller":"traceutil/trace.go:171","msg":"trace[499149333] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"197.782996ms","start":"2026-08-12T17:29:59.369033Z","end":"2026-08-12T17:29:59.566816Z","steps":["trace[499149333] 'process raft request' (duration: 197.550362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:59.566995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.165291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-08-12T17:29:59.567062Z","caller":"traceutil/trace.go:171","msg":"trace[1167364095] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:962; }","duration":"231.255123ms","start":"2026-08-12T17:29:59.335786Z","end":"2026-08-12T17:29:59.567042Z","steps":["trace[1167364095] 'agreement among raft nodes before linearized reading' (duration: 231.05522ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:29:59.819226Z","caller":"traceutil/trace.go:171","msg":"trace[1678989248] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"252.474453ms","start":"2026-08-12T17:29:59.56673Z","end":"2026-08-12T17:29:59.819204Z","steps":["trace[1678989248] 'read index received' (duration: 248.109296ms)","trace[1678989248] 'applied index is now lower than readState.Index' (duration: 4.364517ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:59.819502Z","caller":"traceutil/trace.go:171","msg":"trace[653284040] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"325.044233ms","start":"2026-08-12T17:29:59.494437Z","end":"2026-08-12T17:29:59.819481Z","steps":["trace[653284040] 'process raft request' (duration: 320.385842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:59.819766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.711514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-08-12T17:29:59.819833Z","caller":"traceutil/trace.go:171","msg":"trace[1340734154] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:963; }","duration":"302.812336ms","start":"2026-08-12T17:29:59.51701Z","end":"2026-08-12T17:29:59.819822Z","steps":["trace[1340734154] 'agreement among raft nodes before linearized reading' (duration: 302.499711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:59.819926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:59.516994Z","time spent":"302.900427ms","remote":"127.0.0.1:39578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":920,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"warn","ts":"2026-08-12T17:29:59.819772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:59.494403Z","time spent":"325.136534ms","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3595,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:29:59.94232Z","caller":"traceutil/trace.go:171","msg":"trace[31816636] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"122.948002ms","start":"2026-08-12T17:29:59.81935Z","end":"2026-08-12T17:29:59.942298Z","steps":["trace[31816636] 'read index received' (duration: 114.862759ms)","trace[31816636] 'applied index is now lower than readState.Index' (duration: 8.083983ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:29:59.942384Z","caller":"traceutil/trace.go:171","msg":"trace[919600076] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"368.554842ms","start":"2026-08-12T17:29:59.573788Z","end":"2026-08-12T17:29:59.942343Z","steps":["trace[919600076] 'process raft request' (duration: 360.311437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:59.942502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.488469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:29:59.942534Z","caller":"traceutil/trace.go:171","msg":"trace[1909893067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"183.575359ms","start":"2026-08-12T17:29:59.75895Z","end":"2026-08-12T17:29:59.942525Z","steps":["trace[1909893067] 'agreement among raft nodes before linearized reading' (duration: 183.489838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:59.942658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:29:59.573765Z","time spent":"368.707284ms","remote":"127.0.0.1:39818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3720,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:29:59.942739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.508578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:29:59.942818Z","caller":"traceutil/trace.go:171","msg":"trace[683293954] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:964; }","duration":"212.64505ms","start":"2026-08-12T17:29:59.730156Z","end":"2026-08-12T17:29:59.942801Z","steps":["trace[683293954] 'agreement among raft nodes before linearized reading' (duration: 212.522069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:59.94287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.292176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-d87cz\" ","response":"range_response_count:1 size:3610"} {"level":"info","ts":"2026-08-12T17:29:59.942897Z","caller":"traceutil/trace.go:171","msg":"trace[652245315] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-d87cz; range_end:; response_count:1; response_revision:964; }","duration":"121.388268ms","start":"2026-08-12T17:29:59.821502Z","end":"2026-08-12T17:29:59.942891Z","steps":["trace[652245315] 'agreement among raft nodes before linearized reading' (duration: 121.119654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:29:59.943028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.92281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-12T17:29:59.943091Z","caller":"traceutil/trace.go:171","msg":"trace[234521648] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:964; }","duration":"152.016132ms","start":"2026-08-12T17:29:59.79106Z","end":"2026-08-12T17:29:59.943076Z","steps":["trace[234521648] 'agreement among raft nodes before linearized reading' (duration: 151.350433ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:30:22.309311Z","caller":"traceutil/trace.go:171","msg":"trace[1287713554] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"183.101231ms","start":"2026-08-12T17:30:22.126178Z","end":"2026-08-12T17:30:22.309279Z","steps":["trace[1287713554] 'process raft request' (duration: 182.944998ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:30:32.750871Z","caller":"traceutil/trace.go:171","msg":"trace[93091627] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"352.187605ms","start":"2026-08-12T17:30:32.398667Z","end":"2026-08-12T17:30:32.750854Z","steps":["trace[93091627] 'read index received' (duration: 324.540054ms)","trace[93091627] 'applied index is now lower than readState.Index' (duration: 27.646611ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:30:32.750967Z","caller":"traceutil/trace.go:171","msg":"trace[1270229167] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"353.87728ms","start":"2026-08-12T17:30:32.397044Z","end":"2026-08-12T17:30:32.750921Z","steps":["trace[1270229167] 'process raft request' (duration: 326.135598ms)","trace[1270229167] 'compare' (duration: 27.549379ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:30:32.751171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:30:32.39703Z","time spent":"354.040222ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:30:32.751287Z","caller":"traceutil/trace.go:171","msg":"trace[642056823] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"142.304591ms","start":"2026-08-12T17:30:32.608974Z","end":"2026-08-12T17:30:32.751279Z","steps":["trace[642056823] 'process raft request' (duration: 141.857364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:30:32.751427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.734742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-12T17:30:32.751504Z","caller":"traceutil/trace.go:171","msg":"trace[1781628826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1097; }","duration":"352.867704ms","start":"2026-08-12T17:30:32.39862Z","end":"2026-08-12T17:30:32.751487Z","steps":["trace[1781628826] 'agreement among raft nodes before linearized reading' (duration: 352.325686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:30:32.751597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:30:32.398609Z","time spent":"352.931734ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-12T17:30:32.751718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.822711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-12T17:30:32.751872Z","caller":"traceutil/trace.go:171","msg":"trace[859289233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1097; }","duration":"344.013553ms","start":"2026-08-12T17:30:32.40784Z","end":"2026-08-12T17:30:32.751854Z","steps":["trace[859289233] 'agreement among raft nodes before linearized reading' (duration: 343.429695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:30:32.751979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:30:32.407828Z","time spent":"344.137575ms","remote":"127.0.0.1:39650","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":"info","ts":"2026-08-12T17:30:55.743735Z","caller":"traceutil/trace.go:171","msg":"trace[1271776079] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"176.189974ms","start":"2026-08-12T17:30:55.567516Z","end":"2026-08-12T17:30:55.743706Z","steps":["trace[1271776079] 'process raft request' (duration: 175.937235ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:17.286523Z","caller":"traceutil/trace.go:171","msg":"trace[58252260] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"108.340172ms","start":"2026-08-12T17:31:17.178152Z","end":"2026-08-12T17:31:17.286492Z","steps":["trace[58252260] 'process raft request' (duration: 107.924658ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:18.916965Z","caller":"traceutil/trace.go:171","msg":"trace[803641507] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"159.064901ms","start":"2026-08-12T17:31:18.757875Z","end":"2026-08-12T17:31:18.91694Z","steps":["trace[803641507] 'read index received' (duration: 158.942717ms)","trace[803641507] 'applied index is now lower than readState.Index' (duration: 121.184µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:31:18.917081Z","caller":"traceutil/trace.go:171","msg":"trace[1924363818] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"166.374704ms","start":"2026-08-12T17:31:18.75067Z","end":"2026-08-12T17:31:18.917045Z","steps":["trace[1924363818] 'process raft request' (duration: 166.125805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:18.917118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.226136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:31:18.91716Z","caller":"traceutil/trace.go:171","msg":"trace[13594730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"159.300678ms","start":"2026-08-12T17:31:18.75785Z","end":"2026-08-12T17:31:18.917151Z","steps":["trace[13594730] 'agreement among raft nodes before linearized reading' (duration: 159.191855ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:19.290387Z","caller":"traceutil/trace.go:171","msg":"trace[975029561] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"124.398667ms","start":"2026-08-12T17:31:19.165966Z","end":"2026-08-12T17:31:19.290365Z","steps":["trace[975029561] 'read index received' (duration: 124.243621ms)","trace[975029561] 'applied index is now lower than readState.Index' (duration: 153.996µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:31:19.290433Z","caller":"traceutil/trace.go:171","msg":"trace[1215305827] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"228.087118ms","start":"2026-08-12T17:31:19.062318Z","end":"2026-08-12T17:31:19.290405Z","steps":["trace[1215305827] 'process raft request' (duration: 227.905812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:19.290679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.685076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5547"} {"level":"info","ts":"2026-08-12T17:31:19.290758Z","caller":"traceutil/trace.go:171","msg":"trace[425144827] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1248; }","duration":"124.8159ms","start":"2026-08-12T17:31:19.165924Z","end":"2026-08-12T17:31:19.29074Z","steps":["trace[425144827] 'agreement among raft nodes before linearized reading' (duration: 124.565732ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:27.438134Z","caller":"traceutil/trace.go:171","msg":"trace[1880106417] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"126.609058ms","start":"2026-08-12T17:31:27.311503Z","end":"2026-08-12T17:31:27.438112Z","steps":["trace[1880106417] 'process raft request' (duration: 126.269667ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:27.783879Z","caller":"traceutil/trace.go:171","msg":"trace[420644429] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"218.89896ms","start":"2026-08-12T17:31:27.564949Z","end":"2026-08-12T17:31:27.783848Z","steps":["trace[420644429] 'process raft request' (duration: 154.896873ms)","trace[420644429] 'compare' (duration: 63.889984ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:31:27.855851Z","caller":"traceutil/trace.go:171","msg":"trace[84620047] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1337; }","duration":"204.178638ms","start":"2026-08-12T17:31:27.651654Z","end":"2026-08-12T17:31:27.855833Z","steps":["trace[84620047] 'read index received' (duration: 68.207139ms)","trace[84620047] 'applied index is now lower than readState.Index' (duration: 135.970279ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:31:27.856065Z","caller":"traceutil/trace.go:171","msg":"trace[434326521] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"286.479975ms","start":"2026-08-12T17:31:27.56957Z","end":"2026-08-12T17:31:27.85605Z","steps":["trace[434326521] 'process raft request' (duration: 286.186146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:27.856342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.660944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-12T17:31:27.856394Z","caller":"traceutil/trace.go:171","msg":"trace[1061400449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1285; }","duration":"204.779007ms","start":"2026-08-12T17:31:27.6516Z","end":"2026-08-12T17:31:27.856379Z","steps":["trace[1061400449] 'agreement among raft nodes before linearized reading' (duration: 204.617832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:28.219554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.193506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:31:28.219682Z","caller":"traceutil/trace.go:171","msg":"trace[1610458631] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1340; }","duration":"340.535893ms","start":"2026-08-12T17:31:27.879125Z","end":"2026-08-12T17:31:28.21966Z","steps":["trace[1610458631] 'read index received' (duration: 125.80344ms)","trace[1610458631] 'applied index is now lower than readState.Index' (duration: 214.730763ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:31:28.219706Z","caller":"traceutil/trace.go:171","msg":"trace[534761579] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"358.982239ms","start":"2026-08-12T17:31:27.860695Z","end":"2026-08-12T17:31:28.219677Z","steps":["trace[534761579] 'process raft request' (duration: 144.261947ms)","trace[534761579] 'compare' (duration: 213.780862ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:31:28.219821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:27.860678Z","time spent":"359.098304ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:31:28.219962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.790916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40a0a57e-604e-4179-87d2-24ceed45d537\" ","response":"range_response_count:1 size:2513"} {"level":"info","ts":"2026-08-12T17:31:28.220019Z","caller":"traceutil/trace.go:171","msg":"trace[844275219] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-40a0a57e-604e-4179-87d2-24ceed45d537; range_end:; response_count:1; response_revision:1286; }","duration":"359.893819ms","start":"2026-08-12T17:31:27.860112Z","end":"2026-08-12T17:31:28.220006Z","steps":["trace[844275219] 'agreement among raft nodes before linearized reading' (duration: 359.683822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:28.22006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:27.86009Z","time spent":"359.961272ms","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":2536,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40a0a57e-604e-4179-87d2-24ceed45d537\" "} {"level":"warn","ts":"2026-08-12T17:31:28.220045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.59517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40a0a57e-604e-4179-87d2-24ceed45d537\" ","response":"range_response_count:1 size:2513"} {"level":"info","ts":"2026-08-12T17:31:28.220286Z","caller":"traceutil/trace.go:171","msg":"trace[206605996] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-40a0a57e-604e-4179-87d2-24ceed45d537; range_end:; response_count:1; response_revision:1286; }","duration":"350.864829ms","start":"2026-08-12T17:31:27.869333Z","end":"2026-08-12T17:31:28.220198Z","steps":["trace[206605996] 'agreement among raft nodes before linearized reading' (duration: 350.372882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:28.220424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:27.86932Z","time spent":"351.086295ms","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":2536,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40a0a57e-604e-4179-87d2-24ceed45d537\" "} {"level":"warn","ts":"2026-08-12T17:31:28.226938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.29024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:31:28.22692Z","caller":"traceutil/trace.go:171","msg":"trace[1466937700] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"346.708929ms","start":"2026-08-12T17:31:27.880182Z","end":"2026-08-12T17:31:28.226891Z","steps":["trace[1466937700] 'process raft request' (duration: 346.598145ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:28.226982Z","caller":"traceutil/trace.go:171","msg":"trace[569915414] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1287; }","duration":"256.391093ms","start":"2026-08-12T17:31:27.970581Z","end":"2026-08-12T17:31:28.226972Z","steps":["trace[569915414] 'agreement among raft nodes before linearized reading' (duration: 256.27172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:28.227061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:27.880169Z","time spent":"346.822583ms","remote":"127.0.0.1:39404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:31:28.227223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.797687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-08-12T17:31:28.227386Z","caller":"traceutil/trace.go:171","msg":"trace[767978016] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:1287; }","duration":"191.033004ms","start":"2026-08-12T17:31:28.03633Z","end":"2026-08-12T17:31:28.227363Z","steps":["trace[767978016] 'agreement among raft nodes before linearized reading' (duration: 190.58149ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:28.369449Z","caller":"traceutil/trace.go:171","msg":"trace[1493348138] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"141.086454ms","start":"2026-08-12T17:31:28.228335Z","end":"2026-08-12T17:31:28.369422Z","steps":["trace[1493348138] 'read index received' (duration: 139.820891ms)","trace[1493348138] 'applied index is now lower than readState.Index' (duration: 1.264693ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:31:28.369665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.306881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-08-12T17:31:28.369748Z","caller":"traceutil/trace.go:171","msg":"trace[376721681] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"143.043838ms","start":"2026-08-12T17:31:28.226692Z","end":"2026-08-12T17:31:28.369736Z","steps":["trace[376721681] 'process raft request' (duration: 141.487856ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:28.369888Z","caller":"traceutil/trace.go:171","msg":"trace[1825080858] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1288; }","duration":"141.406765ms","start":"2026-08-12T17:31:28.228307Z","end":"2026-08-12T17:31:28.369713Z","steps":["trace[1825080858] 'agreement among raft nodes before linearized reading' (duration: 141.27553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:28.36994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.490937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40a0a57e-604e-4179-87d2-24ceed45d537\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-08-12T17:31:28.370057Z","caller":"traceutil/trace.go:171","msg":"trace[165556348] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-40a0a57e-604e-4179-87d2-24ceed45d537; range_end:; response_count:1; response_revision:1288; }","duration":"141.680633ms","start":"2026-08-12T17:31:28.228351Z","end":"2026-08-12T17:31:28.370031Z","steps":["trace[165556348] 'agreement among raft nodes before linearized reading' (duration: 141.371773ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:31.968092Z","caller":"traceutil/trace.go:171","msg":"trace[2092228776] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1358; }","duration":"422.689531ms","start":"2026-08-12T17:31:31.545365Z","end":"2026-08-12T17:31:31.968055Z","steps":["trace[2092228776] 'read index received' (duration: 23.824194ms)","trace[2092228776] 'applied index is now lower than readState.Index' (duration: 398.864257ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:31:31.968147Z","caller":"traceutil/trace.go:171","msg":"trace[793362933] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"476.281663ms","start":"2026-08-12T17:31:31.491809Z","end":"2026-08-12T17:31:31.968091Z","steps":["trace[793362933] 'process raft request' (duration: 476.079146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:31.968339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.96523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-12T17:31:31.968362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:31.491776Z","time spent":"476.48602ms","remote":"127.0.0.1:39430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":853,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:31:31.96838Z","caller":"traceutil/trace.go:171","msg":"trace[1156734283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1305; }","duration":"423.067194ms","start":"2026-08-12T17:31:31.545303Z","end":"2026-08-12T17:31:31.96837Z","steps":["trace[1156734283] 'agreement among raft nodes before linearized reading' (duration: 422.892798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:31.968424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:31.545277Z","time spent":"423.138535ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T17:31:31.968683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.694505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T17:31:31.968707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.589384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-12T17:31:31.968743Z","caller":"traceutil/trace.go:171","msg":"trace[1114819392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"209.817189ms","start":"2026-08-12T17:31:31.75891Z","end":"2026-08-12T17:31:31.968727Z","steps":["trace[1114819392] 'agreement among raft nodes before linearized reading' (duration: 209.582182ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:31.96879Z","caller":"traceutil/trace.go:171","msg":"trace[1568009970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1305; }","duration":"147.721008ms","start":"2026-08-12T17:31:31.821048Z","end":"2026-08-12T17:31:31.968769Z","steps":["trace[1568009970] 'agreement among raft nodes before linearized reading' (duration: 147.460739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:31.969027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.339784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40a0a57e-604e-4179-87d2-24ceed45d537\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-08-12T17:31:31.969173Z","caller":"traceutil/trace.go:171","msg":"trace[1588628] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-40a0a57e-604e-4179-87d2-24ceed45d537; range_end:; response_count:1; response_revision:1305; }","duration":"299.52999ms","start":"2026-08-12T17:31:31.66962Z","end":"2026-08-12T17:31:31.96915Z","steps":["trace[1588628] 'agreement among raft nodes before linearized reading' (duration: 298.812126ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:32.103793Z","caller":"traceutil/trace.go:171","msg":"trace[1411345185] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"130.593013ms","start":"2026-08-12T17:31:31.973172Z","end":"2026-08-12T17:31:32.103765Z","steps":["trace[1411345185] 'process raft request' (duration: 125.817756ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:32.104016Z","caller":"traceutil/trace.go:171","msg":"trace[2040134914] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"126.052824ms","start":"2026-08-12T17:31:31.977888Z","end":"2026-08-12T17:31:32.103941Z","steps":["trace[2040134914] 'process raft request' (duration: 125.803656ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:36.398798Z","caller":"traceutil/trace.go:171","msg":"trace[1667526] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"124.627214ms","start":"2026-08-12T17:31:36.274145Z","end":"2026-08-12T17:31:36.398773Z","steps":["trace[1667526] 'process raft request' (duration: 124.479259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:38.773226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.758274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:31:38.773302Z","caller":"traceutil/trace.go:171","msg":"trace[1458427079] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"370.466179ms","start":"2026-08-12T17:31:38.402824Z","end":"2026-08-12T17:31:38.77329Z","steps":["trace[1458427079] 'read index received' (duration: 51.561µs)","trace[1458427079] 'applied index is now lower than readState.Index' (duration: 370.413378ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:31:38.773535Z","caller":"traceutil/trace.go:171","msg":"trace[1238263145] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"642.489695ms","start":"2026-08-12T17:31:38.131034Z","end":"2026-08-12T17:31:38.773524Z","steps":["trace[1238263145] 'process raft request' (duration: 141.371349ms)","trace[1238263145] 'compare' (duration: 500.555688ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:31:38.773847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:38.131013Z","time spent":"642.811456ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:31:38.773787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.961056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-12T17:31:38.773941Z","caller":"traceutil/trace.go:171","msg":"trace[723723490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1344; }","duration":"371.124562ms","start":"2026-08-12T17:31:38.40278Z","end":"2026-08-12T17:31:38.773905Z","steps":["trace[723723490] 'agreement among raft nodes before linearized reading' (duration: 370.939846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:38.774044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:38.40276Z","time spent":"371.249515ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-12T17:31:45.035538Z","caller":"traceutil/trace.go:171","msg":"trace[37926598] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"203.840677ms","start":"2026-08-12T17:31:44.831643Z","end":"2026-08-12T17:31:45.035484Z","steps":["trace[37926598] 'process raft request' (duration: 193.545995ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:47.632172Z","caller":"traceutil/trace.go:171","msg":"trace[1967564385] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"101.24063ms","start":"2026-08-12T17:31:47.530906Z","end":"2026-08-12T17:31:47.632147Z","steps":["trace[1967564385] 'process raft request' (duration: 101.082615ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:49.005607Z","caller":"traceutil/trace.go:171","msg":"trace[1202274709] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"159.532461ms","start":"2026-08-12T17:31:48.846052Z","end":"2026-08-12T17:31:49.005584Z","steps":["trace[1202274709] 'process raft request' (duration: 159.391307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:49.38768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.121718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:31:49.388063Z","caller":"traceutil/trace.go:171","msg":"trace[21049949] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1443; }","duration":"181.19367ms","start":"2026-08-12T17:31:49.206846Z","end":"2026-08-12T17:31:49.38804Z","steps":["trace[21049949] 'read index received' (duration: 35.201µs)","trace[21049949] 'applied index is now lower than readState.Index' (duration: 181.156339ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:31:49.388077Z","caller":"traceutil/trace.go:171","msg":"trace[984388839] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"334.185732ms","start":"2026-08-12T17:31:49.053859Z","end":"2026-08-12T17:31:49.388044Z","steps":["trace[984388839] 'process raft request' (duration: 152.612911ms)","trace[984388839] 'compare' (duration: 181.013844ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:31:49.388211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.366646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:31:49.388265Z","caller":"traceutil/trace.go:171","msg":"trace[186279230] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1385; }","duration":"181.454829ms","start":"2026-08-12T17:31:49.206796Z","end":"2026-08-12T17:31:49.388251Z","steps":["trace[186279230] 'agreement among raft nodes before linearized reading' (duration: 181.356556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:49.388729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:49.053843Z","time spent":"334.303777ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:31:49.750199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.536187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-12T17:31:49.750268Z","caller":"traceutil/trace.go:171","msg":"trace[1956488775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1386; }","duration":"113.661762ms","start":"2026-08-12T17:31:49.636594Z","end":"2026-08-12T17:31:49.750256Z","steps":["trace[1956488775] 'range keys from in-memory index tree' (duration: 113.410364ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:50.159996Z","caller":"traceutil/trace.go:171","msg":"trace[1963102550] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1445; }","duration":"399.320343ms","start":"2026-08-12T17:31:49.76065Z","end":"2026-08-12T17:31:50.15997Z","steps":["trace[1963102550] 'read index received' (duration: 399.213599ms)","trace[1963102550] 'applied index is now lower than readState.Index' (duration: 105.544µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:31:50.160209Z","caller":"traceutil/trace.go:171","msg":"trace[374457222] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"406.999828ms","start":"2026-08-12T17:31:49.753159Z","end":"2026-08-12T17:31:50.160159Z","steps":["trace[374457222] 'process raft request' (duration: 406.626247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:50.160357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:49.753137Z","time spent":"407.151543ms","remote":"127.0.0.1:39650","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-08-12T17:31:50.160532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.86605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:31:50.160601Z","caller":"traceutil/trace.go:171","msg":"trace[1815620759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1387; }","duration":"399.991813ms","start":"2026-08-12T17:31:49.760597Z","end":"2026-08-12T17:31:50.160589Z","steps":["trace[1815620759] 'agreement among raft nodes before linearized reading' (duration: 399.846669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:50.160914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:49.760554Z","time spent":"400.2309ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T17:31:50.216288Z","caller":"traceutil/trace.go:171","msg":"trace[694430743] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"448.690776ms","start":"2026-08-12T17:31:49.767569Z","end":"2026-08-12T17:31:50.21626Z","steps":["trace[694430743] 'process raft request' (duration: 448.47327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:50.216433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:49.767543Z","time spent":"448.81577ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:31:50.362468Z","caller":"traceutil/trace.go:171","msg":"trace[1635958697] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"134.581431ms","start":"2026-08-12T17:31:50.227863Z","end":"2026-08-12T17:31:50.362445Z","steps":["trace[1635958697] 'process raft request' (duration: 134.53163ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:50.362513Z","caller":"traceutil/trace.go:171","msg":"trace[1272851042] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"141.509193ms","start":"2026-08-12T17:31:50.220974Z","end":"2026-08-12T17:31:50.362483Z","steps":["trace[1272851042] 'process raft request' (duration: 63.885851ms)","trace[1272851042] 'compare' (duration: 77.413976ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:31:51.374418Z","caller":"traceutil/trace.go:171","msg":"trace[1732225026] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"360.956521ms","start":"2026-08-12T17:31:51.013435Z","end":"2026-08-12T17:31:51.374391Z","steps":["trace[1732225026] 'process raft request' (duration: 360.794925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:51.374576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:51.013413Z","time spent":"361.080845ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:31:51.979718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196700680011613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T17:31:52.4802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16408196700680011613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T17:31:52.704137Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.307465687s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T17:31:52.704526Z","caller":"traceutil/trace.go:171","msg":"trace[51756188] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"1.308062165s","start":"2026-08-12T17:31:51.39644Z","end":"2026-08-12T17:31:52.704502Z","steps":["trace[51756188] 'process raft request' (duration: 1.30789718s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:52.704755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:51.396403Z","time spent":"1.30823703s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:31:53.008003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.758074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:31:53.008098Z","caller":"traceutil/trace.go:171","msg":"trace[1475966356] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1450; }","duration":"1.528864166s","start":"2026-08-12T17:31:51.479217Z","end":"2026-08-12T17:31:53.008082Z","steps":["trace[1475966356] 'read index received' (duration: 1.225128338s)","trace[1475966356] 'applied index is now lower than readState.Index' (duration: 303.734798ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:31:53.008235Z","caller":"traceutil/trace.go:171","msg":"trace[1054042617] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"786.601075ms","start":"2026-08-12T17:31:52.221572Z","end":"2026-08-12T17:31:53.008173Z","steps":["trace[1054042617] 'process raft request' (duration: 658.593384ms)","trace[1054042617] 'compare' (duration: 127.656461ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:31:53.008309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529097753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T17:31:53.008337Z","caller":"traceutil/trace.go:171","msg":"trace[1272251818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1393; }","duration":"1.529165625s","start":"2026-08-12T17:31:51.479162Z","end":"2026-08-12T17:31:53.008328Z","steps":["trace[1272251818] 'agreement among raft nodes before linearized reading' (duration: 1.52899966s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:53.008374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:51.479139Z","time spent":"1.529227727s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-12T17:31:53.008396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:52.221545Z","time spent":"786.76396ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:31:53.008475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247433137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:31:53.008543Z","caller":"traceutil/trace.go:171","msg":"trace[272880254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1393; }","duration":"1.247539851s","start":"2026-08-12T17:31:51.760987Z","end":"2026-08-12T17:31:53.008527Z","steps":["trace[272880254] 'agreement among raft nodes before linearized reading' (duration: 1.247437348s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:53.008618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:51.760967Z","time spent":"1.247633183s","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T17:31:53.008678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.975564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-12T17:31:53.008761Z","caller":"traceutil/trace.go:171","msg":"trace[158219369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1393; }","duration":"844.116368ms","start":"2026-08-12T17:31:52.164622Z","end":"2026-08-12T17:31:53.008738Z","steps":["trace[158219369] 'agreement among raft nodes before linearized reading' (duration: 843.83653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:53.008814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:52.164536Z","time spent":"844.267382ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-12T17:31:53.009218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.320887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-12T17:31:53.009292Z","caller":"traceutil/trace.go:171","msg":"trace[660548628] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1393; }","duration":"642.436ms","start":"2026-08-12T17:31:52.36684Z","end":"2026-08-12T17:31:53.009276Z","steps":["trace[660548628] 'agreement among raft nodes before linearized reading' (duration: 641.602185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:53.009335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:52.366806Z","time spent":"642.520373ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T17:31:53.33085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.453418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:31:53.331013Z","caller":"traceutil/trace.go:171","msg":"trace[1450814292] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1452; }","duration":"313.374209ms","start":"2026-08-12T17:31:53.017623Z","end":"2026-08-12T17:31:53.330997Z","steps":["trace[1450814292] 'read index received' (duration: 87.938281ms)","trace[1450814292] 'applied index is now lower than readState.Index' (duration: 225.435178ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:31:53.331094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.477183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:31:53.331133Z","caller":"traceutil/trace.go:171","msg":"trace[928619177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1396; }","duration":"313.540314ms","start":"2026-08-12T17:31:53.017583Z","end":"2026-08-12T17:31:53.331123Z","steps":["trace[928619177] 'agreement among raft nodes before linearized reading' (duration: 313.484802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:53.331163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:53.017566Z","time spent":"313.591076ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T17:31:53.331146Z","caller":"traceutil/trace.go:171","msg":"trace[2142120382] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"318.33932ms","start":"2026-08-12T17:31:53.012779Z","end":"2026-08-12T17:31:53.331118Z","steps":["trace[2142120382] 'process raft request' (duration: 123.521231ms)","trace[2142120382] 'compare' (duration: 194.267162ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:31:53.331265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:53.012757Z","time spent":"318.444913ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:31:53.331337Z","caller":"traceutil/trace.go:171","msg":"trace[1382495500] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"317.011379ms","start":"2026-08-12T17:31:53.014316Z","end":"2026-08-12T17:31:53.331327Z","steps":["trace[1382495500] 'process raft request' (duration: 316.617947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:31:53.3314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:31:53.014304Z","time spent":"317.064721ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:31:54.922302Z","caller":"traceutil/trace.go:171","msg":"trace[36214998] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"114.120145ms","start":"2026-08-12T17:31:54.80815Z","end":"2026-08-12T17:31:54.92227Z","steps":["trace[36214998] 'process raft request' (duration: 113.820386ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:31:58.87592Z","caller":"traceutil/trace.go:171","msg":"trace[1290729932] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"125.493617ms","start":"2026-08-12T17:31:58.750401Z","end":"2026-08-12T17:31:58.875894Z","steps":["trace[1290729932] 'process raft request' (duration: 125.298811ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:32:05.688494Z","caller":"traceutil/trace.go:171","msg":"trace[583725966] linearizableReadLoop","detail":"{readStateIndex:1501; appliedIndex:1500; }","duration":"138.504271ms","start":"2026-08-12T17:32:05.54997Z","end":"2026-08-12T17:32:05.688474Z","steps":["trace[583725966] 'read index received' (duration: 138.355577ms)","trace[583725966] 'applied index is now lower than readState.Index' (duration: 147.964µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:32:05.688527Z","caller":"traceutil/trace.go:171","msg":"trace[857212093] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"149.861312ms","start":"2026-08-12T17:32:05.538636Z","end":"2026-08-12T17:32:05.688497Z","steps":["trace[857212093] 'process raft request' (duration: 149.735318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:05.688831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.82535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-12T17:32:05.689103Z","caller":"traceutil/trace.go:171","msg":"trace[353221529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1439; }","duration":"139.17318ms","start":"2026-08-12T17:32:05.549907Z","end":"2026-08-12T17:32:05.68908Z","steps":["trace[353221529] 'agreement among raft nodes before linearized reading' (duration: 138.632214ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:32:11.279433Z","caller":"traceutil/trace.go:171","msg":"trace[1293192348] linearizableReadLoop","detail":"{readStateIndex:1519; appliedIndex:1518; }","duration":"241.503102ms","start":"2026-08-12T17:32:11.037912Z","end":"2026-08-12T17:32:11.279415Z","steps":["trace[1293192348] 'read index received' (duration: 241.42936ms)","trace[1293192348] 'applied index is now lower than readState.Index' (duration: 71.152µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:32:11.279511Z","caller":"traceutil/trace.go:171","msg":"trace[1853222122] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"293.531393ms","start":"2026-08-12T17:32:10.985973Z","end":"2026-08-12T17:32:11.279505Z","steps":["trace[1853222122] 'process raft request' (duration: 293.329807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:11.279657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.734799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-08-12T17:32:11.279677Z","caller":"traceutil/trace.go:171","msg":"trace[83948004] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:1456; }","duration":"241.822981ms","start":"2026-08-12T17:32:11.037848Z","end":"2026-08-12T17:32:11.279671Z","steps":["trace[83948004] 'agreement among raft nodes before linearized reading' (duration: 241.756559ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:32:11.329057Z","caller":"traceutil/trace.go:171","msg":"trace[1622427656] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"164.783566ms","start":"2026-08-12T17:32:11.164262Z","end":"2026-08-12T17:32:11.329046Z","steps":["trace[1622427656] 'process raft request' (duration: 164.732795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:11.32923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.189187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-08-12T17:32:11.32931Z","caller":"traceutil/trace.go:171","msg":"trace[1422156936] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:1457; }","duration":"195.324031ms","start":"2026-08-12T17:32:11.133965Z","end":"2026-08-12T17:32:11.329289Z","steps":["trace[1422156936] 'agreement among raft nodes before linearized reading' (duration: 195.154606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:11.534942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.160702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T17:32:11.535023Z","caller":"traceutil/trace.go:171","msg":"trace[1505975782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1457; }","duration":"183.295666ms","start":"2026-08-12T17:32:11.351712Z","end":"2026-08-12T17:32:11.535008Z","steps":["trace[1505975782] 'range keys from in-memory index tree' (duration: 182.980577ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:32:11.672391Z","caller":"traceutil/trace.go:171","msg":"trace[461988196] linearizableReadLoop","detail":"{readStateIndex:1522; appliedIndex:1520; }","duration":"132.652855ms","start":"2026-08-12T17:32:11.539712Z","end":"2026-08-12T17:32:11.672365Z","steps":["trace[461988196] 'read index received' (duration: 57.715931ms)","trace[461988196] 'applied index is now lower than readState.Index' (duration: 74.935934ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:32:11.672478Z","caller":"traceutil/trace.go:171","msg":"trace[170009623] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"132.895463ms","start":"2026-08-12T17:32:11.539551Z","end":"2026-08-12T17:32:11.672446Z","steps":["trace[170009623] 'process raft request' (duration: 95.996017ms)","trace[170009623] 'compare' (duration: 36.69851ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:32:11.672624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.889393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-08-12T17:32:11.672696Z","caller":"traceutil/trace.go:171","msg":"trace[1470463628] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:1459; }","duration":"133.000685ms","start":"2026-08-12T17:32:11.539676Z","end":"2026-08-12T17:32:11.672677Z","steps":["trace[1470463628] 'agreement among raft nodes before linearized reading' (duration: 132.80121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:11.901176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.983206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:32:11.901308Z","caller":"traceutil/trace.go:171","msg":"trace[1652412720] linearizableReadLoop","detail":"{readStateIndex:1523; appliedIndex:1522; }","duration":"138.178694ms","start":"2026-08-12T17:32:11.763108Z","end":"2026-08-12T17:32:11.901287Z","steps":["trace[1652412720] 'read index received' (duration: 18.92943ms)","trace[1652412720] 'applied index is now lower than readState.Index' (duration: 119.238153ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:32:11.901711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.135209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-12T17:32:11.901765Z","caller":"traceutil/trace.go:171","msg":"trace[323421909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1460; }","duration":"134.254673ms","start":"2026-08-12T17:32:11.767495Z","end":"2026-08-12T17:32:11.90175Z","steps":["trace[323421909] 'agreement among raft nodes before linearized reading' (duration: 134.085188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:11.901949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.359405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-08-12T17:32:11.901994Z","caller":"traceutil/trace.go:171","msg":"trace[318758714] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-controller; range_end:; response_count:1; response_revision:1460; }","duration":"134.427027ms","start":"2026-08-12T17:32:11.767554Z","end":"2026-08-12T17:32:11.901981Z","steps":["trace[318758714] 'agreement among raft nodes before linearized reading' (duration: 134.345855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:11.902131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.416211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T17:32:11.902Z","caller":"traceutil/trace.go:171","msg":"trace[1630197695] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"143.776544ms","start":"2026-08-12T17:32:11.758083Z","end":"2026-08-12T17:32:11.90186Z","steps":["trace[1630197695] 'process raft request' (duration: 24.014896ms)","trace[1630197695] 'compare' (duration: 118.864333ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:32:11.90227Z","caller":"traceutil/trace.go:171","msg":"trace[1596995097] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1460; }","duration":"139.163412ms","start":"2026-08-12T17:32:11.76306Z","end":"2026-08-12T17:32:11.902223Z","steps":["trace[1596995097] 'agreement among raft nodes before linearized reading' (duration: 138.36267ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:32:12.024437Z","caller":"traceutil/trace.go:171","msg":"trace[1557331664] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"115.935807ms","start":"2026-08-12T17:32:11.908456Z","end":"2026-08-12T17:32:12.024392Z","steps":["trace[1557331664] 'process raft request' (duration: 97.230314ms)","trace[1557331664] 'compare' (duration: 18.58161ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:32:13.31108Z","caller":"traceutil/trace.go:171","msg":"trace[1843550059] linearizableReadLoop","detail":"{readStateIndex:1525; appliedIndex:1524; }","duration":"296.429641ms","start":"2026-08-12T17:32:13.014626Z","end":"2026-08-12T17:32:13.311056Z","steps":["trace[1843550059] 'read index received' (duration: 296.271237ms)","trace[1843550059] 'applied index is now lower than readState.Index' (duration: 157.224µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:32:13.31119Z","caller":"traceutil/trace.go:171","msg":"trace[981489140] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"297.985795ms","start":"2026-08-12T17:32:13.01317Z","end":"2026-08-12T17:32:13.311155Z","steps":["trace[981489140] 'process raft request' (duration: 297.746288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:13.311359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.718429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-08-12T17:32:13.311435Z","caller":"traceutil/trace.go:171","msg":"trace[924820553] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:1462; }","duration":"296.838582ms","start":"2026-08-12T17:32:13.014581Z","end":"2026-08-12T17:32:13.311419Z","steps":["trace[924820553] 'agreement among raft nodes before linearized reading' (duration: 296.660277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:13.507371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.144704ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-12T17:32:13.507591Z","caller":"traceutil/trace.go:171","msg":"trace[877246058] linearizableReadLoop","detail":"{readStateIndex:1526; appliedIndex:1525; }","duration":"196.333674ms","start":"2026-08-12T17:32:13.311236Z","end":"2026-08-12T17:32:13.50757Z","steps":["trace[877246058] 'read index received' (duration: 48.038436ms)","trace[877246058] 'applied index is now lower than readState.Index' (duration: 148.293648ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:32:13.507721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.29077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:public-info-viewer\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-08-12T17:32:13.507754Z","caller":"traceutil/trace.go:171","msg":"trace[40115015] range","detail":"{range_begin:/registry/clusterrolebindings/system:public-info-viewer; range_end:; response_count:1; response_revision:1462; }","duration":"467.360552ms","start":"2026-08-12T17:32:13.040384Z","end":"2026-08-12T17:32:13.507744Z","steps":["trace[40115015] 'agreement among raft nodes before linearized reading' (duration: 467.28848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:13.507786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:32:13.040368Z","time spent":"467.410923ms","remote":"127.0.0.1:39728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":806,"request content":"key:\"/registry/clusterrolebindings/system:public-info-viewer\" "} {"level":"warn","ts":"2026-08-12T17:32:13.508334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.947257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:32:13.508333Z","caller":"traceutil/trace.go:171","msg":"trace[1699815233] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"174.489723ms","start":"2026-08-12T17:32:13.333809Z","end":"2026-08-12T17:32:13.508299Z","steps":["trace[1699815233] 'process raft request' (duration: 173.663899ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:32:13.508479Z","caller":"traceutil/trace.go:171","msg":"trace[628818337] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1463; }","duration":"148.991988ms","start":"2026-08-12T17:32:13.359376Z","end":"2026-08-12T17:32:13.508368Z","steps":["trace[628818337] 'agreement among raft nodes before linearized reading' (duration: 148.930516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:13.508673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.449083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-08-12T17:32:13.508812Z","caller":"traceutil/trace.go:171","msg":"trace[1705310201] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:1463; }","duration":"193.008089ms","start":"2026-08-12T17:32:13.315782Z","end":"2026-08-12T17:32:13.50879Z","steps":["trace[1705310201] 'agreement among raft nodes before linearized reading' (duration: 192.448923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:13.868581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.229712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:32:13.868699Z","caller":"traceutil/trace.go:171","msg":"trace[1185868957] linearizableReadLoop","detail":"{readStateIndex:1529; appliedIndex:1528; }","duration":"148.660248ms","start":"2026-08-12T17:32:13.720025Z","end":"2026-08-12T17:32:13.868685Z","steps":["trace[1185868957] 'read index received' (duration: 36.21125ms)","trace[1185868957] 'applied index is now lower than readState.Index' (duration: 112.447978ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:32:13.868719Z","caller":"traceutil/trace.go:171","msg":"trace[1522157656] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"167.425252ms","start":"2026-08-12T17:32:13.701277Z","end":"2026-08-12T17:32:13.868702Z","steps":["trace[1522157656] 'process raft request' (duration: 54.994434ms)","trace[1522157656] 'compare' (duration: 112.088218ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:32:13.868786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.759381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-08-12T17:32:13.868806Z","caller":"traceutil/trace.go:171","msg":"trace[1555288530] range","detail":"{range_begin:/registry/clusterrolebindings/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1465; }","duration":"148.800792ms","start":"2026-08-12T17:32:13.719998Z","end":"2026-08-12T17:32:13.868799Z","steps":["trace[1555288530] 'agreement among raft nodes before linearized reading' (duration: 148.755771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:32:13.869197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.366752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:volume-scheduler\" ","response":"range_response_count:1 size:715"} {"level":"info","ts":"2026-08-12T17:32:13.869279Z","caller":"traceutil/trace.go:171","msg":"trace[1844879370] range","detail":"{range_begin:/registry/clusterrolebindings/system:volume-scheduler; range_end:; response_count:1; response_revision:1465; }","duration":"140.487335ms","start":"2026-08-12T17:32:13.728774Z","end":"2026-08-12T17:32:13.869261Z","steps":["trace[1844879370] 'agreement among raft nodes before linearized reading' (duration: 140.090834ms)"],"step_count":1}