{"level":"warn","ts":"2026-08-20T01:13:16.188982Z","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-20T01:13:16.189091Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.222: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.222:2380","--initial-cluster=instance=https://199.19.213.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.222: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-20T01:13:16.189191Z","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-20T01:13:16.189207Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-08-20T01:13:16.18923Z","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-20T01:13:16.190173Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"]} {"level":"info","ts":"2026-08-20T01:13:16.190268Z","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.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.222: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-20T01:13:16.194758Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.246135ms"} {"level":"info","ts":"2026-08-20T01:13:16.217668Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b78f8b1ca6063edc","cluster-id":"eff609df5818f5a9"} {"level":"info","ts":"2026-08-20T01:13:16.217808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T01:13:16.217833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 0"} {"level":"info","ts":"2026-08-20T01:13:16.217844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b78f8b1ca6063edc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T01:13:16.217854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 1"} {"level":"info","ts":"2026-08-20T01:13:16.217905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"warn","ts":"2026-08-20T01:13:16.226749Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T01:13:16.231557Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T01:13:16.232744Z","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-20T01:13:16.234756Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b78f8b1ca6063edc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T01:13:16.235263Z","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-20T01:13:16.235361Z","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-20T01:13:16.235375Z","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-20T01:13:16.236081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"info","ts":"2026-08-20T01:13:16.236288Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","added-peer-id":"b78f8b1ca6063edc","added-peer-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-08-20T01:13:16.236521Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b78f8b1ca6063edc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T01:13:16.237691Z","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-20T01:13:16.237832Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-08-20T01:13:16.237901Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-08-20T01:13:16.238041Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b78f8b1ca6063edc","initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T01:13:16.238102Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T01:13:16.318421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T01:13:16.318531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T01:13:16.318563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgPreVoteResp from b78f8b1ca6063edc at term 1"} {"level":"info","ts":"2026-08-20T01:13:16.318582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became candidate at term 2"} {"level":"info","ts":"2026-08-20T01:13:16.318589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgVoteResp from b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-08-20T01:13:16.318601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became leader at term 2"} {"level":"info","ts":"2026-08-20T01:13:16.318916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b78f8b1ca6063edc elected leader b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-08-20T01:13:16.32004Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b78f8b1ca6063edc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.222:2379]}","request-path":"/0/members/b78f8b1ca6063edc/attributes","cluster-id":"eff609df5818f5a9","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T01:13:16.320037Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:13:16.32007Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:13:16.320131Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:13:16.320363Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T01:13:16.320402Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T01:13:16.321491Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:13:16.321677Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:13:16.321807Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:13:16.322104Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-20T01:13:16.325303Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.222:2379"} {"level":"info","ts":"2026-08-20T01:13:52.552219Z","caller":"traceutil/trace.go:171","msg":"trace[543530375] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"176.646762ms","start":"2026-08-20T01:13:52.375549Z","end":"2026-08-20T01:13:52.552196Z","steps":["trace[543530375] 'read index received' (duration: 176.374767ms)","trace[543530375] 'applied index is now lower than readState.Index' (duration: 270.995µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:13:52.552637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.064451ms","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:12657"} {"level":"warn","ts":"2026-08-20T01:13:52.552645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.612616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-20T01:13:52.552728Z","caller":"traceutil/trace.go:171","msg":"trace[1446308491] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:449; }","duration":"177.200203ms","start":"2026-08-20T01:13:52.375512Z","end":"2026-08-20T01:13:52.552712Z","steps":["trace[1446308491] 'agreement among raft nodes before linearized reading' (duration: 177.04783ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:52.55268Z","caller":"traceutil/trace.go:171","msg":"trace[1799751833] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"188.058974ms","start":"2026-08-20T01:13:52.364559Z","end":"2026-08-20T01:13:52.552618Z","steps":["trace[1799751833] 'process raft request' (duration: 187.503704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:52.552755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.17414ms","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-20T01:13:52.552826Z","caller":"traceutil/trace.go:171","msg":"trace[1216503483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:449; }","duration":"138.304583ms","start":"2026-08-20T01:13:52.414506Z","end":"2026-08-20T01:13:52.552811Z","steps":["trace[1216503483] 'agreement among raft nodes before linearized reading' (duration: 138.16746ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:52.552807Z","caller":"traceutil/trace.go:171","msg":"trace[1610677018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:449; }","duration":"153.81146ms","start":"2026-08-20T01:13:52.398954Z","end":"2026-08-20T01:13:52.552766Z","steps":["trace[1610677018] 'agreement among raft nodes before linearized reading' (duration: 153.595556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:54.879639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.430965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-20T01:13:54.879758Z","caller":"traceutil/trace.go:171","msg":"trace[288568921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:489; }","duration":"114.602487ms","start":"2026-08-20T01:13:54.765133Z","end":"2026-08-20T01:13:54.879735Z","steps":["trace[288568921] 'range keys from in-memory index tree' (duration: 114.255301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:55.650734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.233838ms","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-20T01:13:55.65083Z","caller":"traceutil/trace.go:171","msg":"trace[284272007] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:490; }","duration":"626.392951ms","start":"2026-08-20T01:13:55.02442Z","end":"2026-08-20T01:13:55.650813Z","steps":["trace[284272007] 'range keys from in-memory index tree' (duration: 626.074035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:55.650875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:55.024358Z","time spent":"626.499373ms","remote":"127.0.0.1:45068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-08-20T01:13:57.855283Z","caller":"traceutil/trace.go:171","msg":"trace[906234093] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"149.321121ms","start":"2026-08-20T01:13:57.70593Z","end":"2026-08-20T01:13:57.855251Z","steps":["trace[906234093] 'process raft request' (duration: 148.913063ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:58.072241Z","caller":"traceutil/trace.go:171","msg":"trace[2126573527] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"135.926683ms","start":"2026-08-20T01:13:57.936284Z","end":"2026-08-20T01:13:58.072211Z","steps":["trace[2126573527] 'process raft request' (duration: 119.954415ms)","trace[2126573527] 'compare' (duration: 15.827995ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:02.408231Z","caller":"traceutil/trace.go:171","msg":"trace[1982200487] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"227.596168ms","start":"2026-08-20T01:14:02.180614Z","end":"2026-08-20T01:14:02.40821Z","steps":["trace[1982200487] 'process raft request' (duration: 226.111231ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:02.423626Z","caller":"traceutil/trace.go:171","msg":"trace[701086372] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"169.356348ms","start":"2026-08-20T01:14:02.254237Z","end":"2026-08-20T01:14:02.423594Z","steps":["trace[701086372] 'process raft request' (duration: 169.200415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:03.525623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.963008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:14:03.52578Z","caller":"traceutil/trace.go:171","msg":"trace[213679235] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"952.311696ms","start":"2026-08-20T01:14:02.573447Z","end":"2026-08-20T01:14:03.525735Z","steps":["trace[213679235] 'read index received' (duration: 37.585423ms)","trace[213679235] 'applied index is now lower than readState.Index' (duration: 914.701512ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:03.525828Z","caller":"traceutil/trace.go:171","msg":"trace[676385368] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.054557508s","start":"2026-08-20T01:14:02.47123Z","end":"2026-08-20T01:14:03.525787Z","steps":["trace[676385368] 'process raft request' (duration: 139.855836ms)","trace[676385368] 'compare' (duration: 913.792945ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:03.525944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:02.471209Z","time spent":"1.054684531s","remote":"127.0.0.1:45192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T01:14:03.52619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.728013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-98tj6\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-08-20T01:14:03.526298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.689663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-20T01:14:03.526357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.347075ms","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-20T01:14:03.526376Z","caller":"traceutil/trace.go:171","msg":"trace[167553469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"890.796015ms","start":"2026-08-20T01:14:02.635563Z","end":"2026-08-20T01:14:03.526359Z","steps":["trace[167553469] 'agreement among raft nodes before linearized reading' (duration: 890.50626ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:03.526403Z","caller":"traceutil/trace.go:171","msg":"trace[1573671078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"891.427507ms","start":"2026-08-20T01:14:02.634958Z","end":"2026-08-20T01:14:03.526385Z","steps":["trace[1573671078] 'agreement among raft nodes before linearized reading' (duration: 891.304714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:03.526426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:02.634933Z","time spent":"891.486788ms","remote":"127.0.0.1:45256","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-20T01:14:03.526426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:02.635553Z","time spent":"890.863796ms","remote":"127.0.0.1:45256","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-20T01:14:03.526451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.269555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:03.526358Z","caller":"traceutil/trace.go:171","msg":"trace[847352705] range","detail":"{range_begin:/registry/pods/kube-system/cilium-98tj6; range_end:; response_count:1; response_revision:589; }","duration":"952.828006ms","start":"2026-08-20T01:14:02.573412Z","end":"2026-08-20T01:14:03.52624Z","steps":["trace[847352705] 'agreement among raft nodes before linearized reading' (duration: 952.471489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:03.52653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.331713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-20T01:14:03.52657Z","caller":"traceutil/trace.go:171","msg":"trace[630821096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"906.443688ms","start":"2026-08-20T01:14:02.620089Z","end":"2026-08-20T01:14:03.526533Z","steps":["trace[630821096] 'agreement among raft nodes before linearized reading' (duration: 906.233144ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:03.526581Z","caller":"traceutil/trace.go:171","msg":"trace[202913531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"606.387184ms","start":"2026-08-20T01:14:02.920178Z","end":"2026-08-20T01:14:03.526565Z","steps":["trace[202913531] 'agreement among raft nodes before linearized reading' (duration: 606.20083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:03.526624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:02.620061Z","time spent":"906.55447ms","remote":"127.0.0.1:44952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-20T01:14:03.526717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:02.573392Z","time spent":"953.160252ms","remote":"127.0.0.1:45170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-98tj6\" "} {"level":"warn","ts":"2026-08-20T01:14:03.52675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:02.920114Z","time spent":"606.507556ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-20T01:14:03.527219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.678155ms","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-20T01:14:03.527937Z","caller":"traceutil/trace.go:171","msg":"trace[1182340042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"920.423239ms","start":"2026-08-20T01:14:02.607498Z","end":"2026-08-20T01:14:03.527921Z","steps":["trace[1182340042] 'agreement among raft nodes before linearized reading' (duration: 918.434172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:03.528034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:02.607453Z","time spent":"920.570902ms","remote":"127.0.0.1:45256","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-20T01:14:05.902552Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.061503458s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-20T01:14:05.903642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.062536837s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:14:05.904054Z","caller":"traceutil/trace.go:171","msg":"trace[175026390] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"2.371631505s","start":"2026-08-20T01:14:03.53239Z","end":"2026-08-20T01:14:05.904022Z","steps":["trace[175026390] 'process raft request' (duration: 2.37135483s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:05.904222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:03.532382Z","time spent":"2.371741077s","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:05.903911Z","caller":"traceutil/trace.go:171","msg":"trace[1414506933] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"2.371958922s","start":"2026-08-20T01:14:03.531914Z","end":"2026-08-20T01:14:05.903873Z","steps":["trace[1414506933] 'process raft request' (duration: 308.988936ms)","trace[1414506933] 'compare' (duration: 2.06211117s)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:05.904534Z","caller":"traceutil/trace.go:171","msg":"trace[1558202001] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:609; }","duration":"2.366032081s","start":"2026-08-20T01:14:03.538485Z","end":"2026-08-20T01:14:05.904517Z","steps":["trace[1558202001] 'read index received' (duration: 302.561996ms)","trace[1558202001] 'applied index is now lower than readState.Index' (duration: 2.063469275s)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:05.90461Z","caller":"traceutil/trace.go:171","msg":"trace[931057056] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"2.372134685s","start":"2026-08-20T01:14:03.532466Z","end":"2026-08-20T01:14:05.904601Z","steps":["trace[931057056] 'process raft request' (duration: 2.37133735s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:05.904638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:03.531883Z","time spent":"2.372676915s","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:05.904681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:03.53244Z","time spent":"2.372201486s","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:05.904772Z","caller":"traceutil/trace.go:171","msg":"trace[1378711568] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"2.371669446s","start":"2026-08-20T01:14:03.533089Z","end":"2026-08-20T01:14:05.904758Z","steps":["trace[1378711568] 'process raft request' (duration: 2.370746029s)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:05.904813Z","caller":"traceutil/trace.go:171","msg":"trace[429117860] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"2.362742579s","start":"2026-08-20T01:14:03.54206Z","end":"2026-08-20T01:14:05.904803Z","steps":["trace[429117860] 'process raft request' (duration: 2.361811572s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:05.904823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:03.533081Z","time spent":"2.371714287s","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:05.904859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:03.542051Z","time spent":"2.36278355s","remote":"127.0.0.1:45170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:05.904949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.366463979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:05.904994Z","caller":"traceutil/trace.go:171","msg":"trace[1424756778] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:594; }","duration":"2.36654201s","start":"2026-08-20T01:14:03.538435Z","end":"2026-08-20T01:14:05.904977Z","steps":["trace[1424756778] 'agreement among raft nodes before linearized reading' (duration: 2.366469799s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:05.905031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:03.538425Z","time spent":"2.366597661s","remote":"127.0.0.1:45306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":29,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-08-20T01:14:05.942839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32351473s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:05.942907Z","caller":"traceutil/trace.go:171","msg":"trace[345155790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"1.323632342s","start":"2026-08-20T01:14:04.619262Z","end":"2026-08-20T01:14:05.942894Z","steps":["trace[345155790] 'agreement among raft nodes before linearized reading' (duration: 1.32351349s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:05.94294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:04.619238Z","time spent":"1.323696034s","remote":"127.0.0.1:44952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-20T01:14:09.114828Z","caller":"traceutil/trace.go:171","msg":"trace[261635182] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"170.976227ms","start":"2026-08-20T01:14:08.943817Z","end":"2026-08-20T01:14:09.114793Z","steps":["trace[261635182] 'process raft request' (duration: 78.176991ms)","trace[261635182] 'compare' (duration: 92.573802ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:09.114915Z","caller":"traceutil/trace.go:171","msg":"trace[805211373] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:639; }","duration":"164.647238ms","start":"2026-08-20T01:14:08.950242Z","end":"2026-08-20T01:14:09.114889Z","steps":["trace[805211373] 'read index received' (duration: 17.985988ms)","trace[805211373] 'applied index is now lower than readState.Index' (duration: 146.65917ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:09.115069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.809501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:09.115132Z","caller":"traceutil/trace.go:171","msg":"trace[1989263754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"164.918384ms","start":"2026-08-20T01:14:08.950194Z","end":"2026-08-20T01:14:09.115112Z","steps":["trace[1989263754] 'agreement among raft nodes before linearized reading' (duration: 164.765701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:09.362655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.725236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:14:09.362788Z","caller":"traceutil/trace.go:171","msg":"trace[823313707] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"226.82873ms","start":"2026-08-20T01:14:09.135944Z","end":"2026-08-20T01:14:09.362772Z","steps":["trace[823313707] 'process raft request' (duration: 91.88568ms)","trace[823313707] 'compare' (duration: 134.551732ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:09.387273Z","caller":"traceutil/trace.go:171","msg":"trace[422994980] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"237.115402ms","start":"2026-08-20T01:14:09.150138Z","end":"2026-08-20T01:14:09.387253Z","steps":["trace[422994980] 'process raft request' (duration: 236.913069ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:09.387282Z","caller":"traceutil/trace.go:171","msg":"trace[725853346] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"236.945769ms","start":"2026-08-20T01:14:09.150291Z","end":"2026-08-20T01:14:09.387237Z","steps":["trace[725853346] 'process raft request' (duration: 236.819617ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:09.387598Z","caller":"traceutil/trace.go:171","msg":"trace[914378783] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"236.072613ms","start":"2026-08-20T01:14:09.151467Z","end":"2026-08-20T01:14:09.387539Z","steps":["trace[914378783] 'process raft request' (duration: 235.682645ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:09.387672Z","caller":"traceutil/trace.go:171","msg":"trace[1018458771] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"209.996502ms","start":"2026-08-20T01:14:09.177659Z","end":"2026-08-20T01:14:09.387655Z","steps":["trace[1018458771] 'process raft request' (duration: 209.945261ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:09.387763Z","caller":"traceutil/trace.go:171","msg":"trace[61419161] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"234.962253ms","start":"2026-08-20T01:14:09.152785Z","end":"2026-08-20T01:14:09.387747Z","steps":["trace[61419161] 'process raft request' (duration: 234.418152ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:09.727061Z","caller":"traceutil/trace.go:171","msg":"trace[470098903] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"109.561992ms","start":"2026-08-20T01:14:09.61748Z","end":"2026-08-20T01:14:09.727042Z","steps":["trace[470098903] 'read index received' (duration: 109.518762ms)","trace[470098903] 'applied index is now lower than readState.Index' (duration: 42.29µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:09.727142Z","caller":"traceutil/trace.go:171","msg":"trace[1777099195] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"111.407437ms","start":"2026-08-20T01:14:09.615683Z","end":"2026-08-20T01:14:09.727091Z","steps":["trace[1777099195] 'process raft request' (duration: 111.219804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:09.727192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.690165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:09.727383Z","caller":"traceutil/trace.go:171","msg":"trace[334001745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"109.903069ms","start":"2026-08-20T01:14:09.61747Z","end":"2026-08-20T01:14:09.727373Z","steps":["trace[334001745] 'agreement among raft nodes before linearized reading' (duration: 109.654384ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:10.158393Z","caller":"traceutil/trace.go:171","msg":"trace[1944919836] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"388.369682ms","start":"2026-08-20T01:14:09.769993Z","end":"2026-08-20T01:14:10.158362Z","steps":["trace[1944919836] 'read index received' (duration: 383.354677ms)","trace[1944919836] 'applied index is now lower than readState.Index' (duration: 5.013675ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:10.1586Z","caller":"traceutil/trace.go:171","msg":"trace[1343928595] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"531.895573ms","start":"2026-08-20T01:14:09.626679Z","end":"2026-08-20T01:14:10.158575Z","steps":["trace[1343928595] 'process raft request' (duration: 526.731816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:10.158801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:09.626644Z","time spent":"532.057236ms","remote":"127.0.0.1:45170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18052,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:10.158831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.81203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.222\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-20T01:14:10.1589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.764344ms","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-20T01:14:10.158921Z","caller":"traceutil/trace.go:171","msg":"trace[2085987261] range","detail":"{range_begin:/registry/masterleases/199.19.213.222; range_end:; response_count:1; response_revision:630; }","duration":"388.971833ms","start":"2026-08-20T01:14:09.769923Z","end":"2026-08-20T01:14:10.158895Z","steps":["trace[2085987261] 'agreement among raft nodes before linearized reading' (duration: 388.639797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:10.158974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:09.769883Z","time spent":"389.080315ms","remote":"127.0.0.1:44982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.222\" "} {"level":"info","ts":"2026-08-20T01:14:10.158949Z","caller":"traceutil/trace.go:171","msg":"trace[1024385525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"240.867326ms","start":"2026-08-20T01:14:09.918069Z","end":"2026-08-20T01:14:10.158936Z","steps":["trace[1024385525] 'agreement among raft nodes before linearized reading' (duration: 240.685532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:10.15914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.299876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-20T01:14:10.159199Z","caller":"traceutil/trace.go:171","msg":"trace[1273865691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"231.382117ms","start":"2026-08-20T01:14:09.9278Z","end":"2026-08-20T01:14:10.159183Z","steps":["trace[1273865691] 'agreement among raft nodes before linearized reading' (duration: 231.200274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:10.159256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.695501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-20T01:14:10.15918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.598662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-20T01:14:10.159314Z","caller":"traceutil/trace.go:171","msg":"trace[219054331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"230.785607ms","start":"2026-08-20T01:14:09.928512Z","end":"2026-08-20T01:14:10.159297Z","steps":["trace[219054331] 'agreement among raft nodes before linearized reading' (duration: 230.413149ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:10.160151Z","caller":"traceutil/trace.go:171","msg":"trace[1571891634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"233.792163ms","start":"2026-08-20T01:14:09.925497Z","end":"2026-08-20T01:14:10.159289Z","steps":["trace[1571891634] 'agreement among raft nodes before linearized reading' (duration: 233.660801ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:11.30771Z","caller":"traceutil/trace.go:171","msg":"trace[1876923987] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"101.459943ms","start":"2026-08-20T01:14:11.206226Z","end":"2026-08-20T01:14:11.307686Z","steps":["trace[1876923987] 'process raft request' (duration: 101.320191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:11.912438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.198265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-98tj6\" ","response":"range_response_count:1 size:18067"} {"level":"info","ts":"2026-08-20T01:14:11.912574Z","caller":"traceutil/trace.go:171","msg":"trace[1119779275] range","detail":"{range_begin:/registry/pods/kube-system/cilium-98tj6; range_end:; response_count:1; response_revision:637; }","duration":"285.381968ms","start":"2026-08-20T01:14:11.627162Z","end":"2026-08-20T01:14:11.912544Z","steps":["trace[1119779275] 'range keys from in-memory index tree' (duration: 284.95908ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:12.141018Z","caller":"traceutil/trace.go:171","msg":"trace[1525100444] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"210.656473ms","start":"2026-08-20T01:14:11.930338Z","end":"2026-08-20T01:14:12.140994Z","steps":["trace[1525100444] 'process raft request' (duration: 210.458289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:12.695081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.573524ms","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-20T01:14:12.695157Z","caller":"traceutil/trace.go:171","msg":"trace[1538428071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"446.699927ms","start":"2026-08-20T01:14:12.248439Z","end":"2026-08-20T01:14:12.695139Z","steps":["trace[1538428071] 'range keys from in-memory index tree' (duration: 446.160287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:12.695209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:12.248408Z","time spent":"446.791019ms","remote":"127.0.0.1:45256","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-20T01:14:12.69524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.513433ms","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-20T01:14:12.695294Z","caller":"traceutil/trace.go:171","msg":"trace[1622517433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"446.594696ms","start":"2026-08-20T01:14:12.248688Z","end":"2026-08-20T01:14:12.695283Z","steps":["trace[1622517433] 'range keys from in-memory index tree' (duration: 446.418882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:12.695321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:12.248681Z","time spent":"446.635296ms","remote":"127.0.0.1:45256","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-20T01:14:12.695451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.214448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-20T01:14:12.69547Z","caller":"traceutil/trace.go:171","msg":"trace[1251742200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"446.265929ms","start":"2026-08-20T01:14:12.2492Z","end":"2026-08-20T01:14:12.695466Z","steps":["trace[1251742200] 'range keys from in-memory index tree' (duration: 446.134987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:12.695483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:12.249179Z","time spent":"446.301019ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-20T01:14:12.695555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.995224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-20T01:14:12.695572Z","caller":"traceutil/trace.go:171","msg":"trace[1893943184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"446.031925ms","start":"2026-08-20T01:14:12.249537Z","end":"2026-08-20T01:14:12.695568Z","steps":["trace[1893943184] 'range keys from in-memory index tree' (duration: 445.932173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:12.695584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:12.249524Z","time spent":"446.057555ms","remote":"127.0.0.1:45256","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":"info","ts":"2026-08-20T01:14:13.180852Z","caller":"traceutil/trace.go:171","msg":"trace[1836324251] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"479.936737ms","start":"2026-08-20T01:14:12.700878Z","end":"2026-08-20T01:14:13.180815Z","steps":["trace[1836324251] 'process raft request' (duration: 479.851325ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:13.180864Z","caller":"traceutil/trace.go:171","msg":"trace[734181456] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"480.861134ms","start":"2026-08-20T01:14:12.699964Z","end":"2026-08-20T01:14:13.180825Z","steps":["trace[734181456] 'process raft request' (duration: 480.722041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:13.18128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:12.700867Z","time spent":"480.208822ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:13.180854Z","caller":"traceutil/trace.go:171","msg":"trace[58773725] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"480.918446ms","start":"2026-08-20T01:14:12.699889Z","end":"2026-08-20T01:14:13.180807Z","steps":["trace[58773725] 'process raft request' (duration: 480.60614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:13.181359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:12.699922Z","time spent":"481.305653ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:13.180988Z","caller":"traceutil/trace.go:171","msg":"trace[55258211] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"480.060649ms","start":"2026-08-20T01:14:12.700918Z","end":"2026-08-20T01:14:13.180979Z","steps":["trace[55258211] 'process raft request' (duration: 479.852485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:13.1815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:12.699861Z","time spent":"481.534127ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:13.181612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:12.700897Z","time spent":"480.585289ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:13.216882Z","caller":"traceutil/trace.go:171","msg":"trace[1593829140] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"503.879969ms","start":"2026-08-20T01:14:12.712973Z","end":"2026-08-20T01:14:13.216853Z","steps":["trace[1593829140] 'process raft request' (duration: 503.677535ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:13.216875Z","caller":"traceutil/trace.go:171","msg":"trace[967480813] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:661; }","duration":"503.516152ms","start":"2026-08-20T01:14:12.713328Z","end":"2026-08-20T01:14:13.216844Z","steps":["trace[967480813] 'read index received' (duration: 467.169656ms)","trace[967480813] 'applied index is now lower than readState.Index' (duration: 36.344846ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:13.21703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:12.712961Z","time spent":"503.988631ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T01:14:13.217199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.275535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-98tj6\" ","response":"range_response_count:1 size:18251"} {"level":"info","ts":"2026-08-20T01:14:13.21728Z","caller":"traceutil/trace.go:171","msg":"trace[1632017862] range","detail":"{range_begin:/registry/pods/kube-system/cilium-98tj6; range_end:; response_count:1; response_revision:644; }","duration":"505.392318ms","start":"2026-08-20T01:14:12.711862Z","end":"2026-08-20T01:14:13.217254Z","steps":["trace[1632017862] 'agreement among raft nodes before linearized reading' (duration: 505.068412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:13.217341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:12.711846Z","time spent":"505.479669ms","remote":"127.0.0.1:45170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18275,"request content":"key:\"/registry/pods/kube-system/cilium-98tj6\" "} {"level":"warn","ts":"2026-08-20T01:14:13.478903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.539676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:14:13.479047Z","caller":"traceutil/trace.go:171","msg":"trace[1955766622] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"236.801471ms","start":"2026-08-20T01:14:13.242222Z","end":"2026-08-20T01:14:13.479024Z","steps":["trace[1955766622] 'process raft request' (duration: 63.01796ms)","trace[1955766622] 'compare' (duration: 173.383263ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:15.18388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.644098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-98tj6\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-08-20T01:14:15.18394Z","caller":"traceutil/trace.go:171","msg":"trace[1443498054] range","detail":"{range_begin:/registry/pods/kube-system/cilium-98tj6; range_end:; response_count:1; response_revision:647; }","duration":"458.741ms","start":"2026-08-20T01:14:14.725182Z","end":"2026-08-20T01:14:15.183923Z","steps":["trace[1443498054] 'range keys from in-memory index tree' (duration: 458.467504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:15.183975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:14.725155Z","time spent":"458.813082ms","remote":"127.0.0.1:45170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18361,"request content":"key:\"/registry/pods/kube-system/cilium-98tj6\" "} {"level":"warn","ts":"2026-08-20T01:14:15.183729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.651842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:15.184403Z","caller":"traceutil/trace.go:171","msg":"trace[597213950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"564.347875ms","start":"2026-08-20T01:14:14.620043Z","end":"2026-08-20T01:14:15.18439Z","steps":["trace[597213950] 'range keys from in-memory index tree' (duration: 563.533539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:15.18443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:14.619989Z","time spent":"564.432606ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-20T01:14:15.284607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.427547ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-08-20T01:14:15.290693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.64867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-20T01:14:15.290828Z","caller":"traceutil/trace.go:171","msg":"trace[243876734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"103.851184ms","start":"2026-08-20T01:14:15.186954Z","end":"2026-08-20T01:14:15.290806Z","steps":["trace[243876734] 'agreement among raft nodes before linearized reading' (duration: 103.574148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:15.290884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.564818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T01:14:15.290938Z","caller":"traceutil/trace.go:171","msg":"trace[200716375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"103.63501ms","start":"2026-08-20T01:14:15.187286Z","end":"2026-08-20T01:14:15.290921Z","steps":["trace[200716375] 'agreement among raft nodes before linearized reading' (duration: 103.497857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:15.291245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.68768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-20T01:14:15.291378Z","caller":"traceutil/trace.go:171","msg":"trace[730193822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"103.942106ms","start":"2026-08-20T01:14:15.187385Z","end":"2026-08-20T01:14:15.291327Z","steps":["trace[730193822] 'agreement among raft nodes before linearized reading' (duration: 103.356264ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:15.481993Z","caller":"traceutil/trace.go:171","msg":"trace[512790248] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"185.857453ms","start":"2026-08-20T01:14:15.296109Z","end":"2026-08-20T01:14:15.481967Z","steps":["trace[512790248] 'process raft request' (duration: 185.809612ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:15.482117Z","caller":"traceutil/trace.go:171","msg":"trace[148192189] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"186.342862ms","start":"2026-08-20T01:14:15.295732Z","end":"2026-08-20T01:14:15.482075Z","steps":["trace[148192189] 'process raft request' (duration: 186.144959ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:15.482164Z","caller":"traceutil/trace.go:171","msg":"trace[1494024152] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"186.361362ms","start":"2026-08-20T01:14:15.295713Z","end":"2026-08-20T01:14:15.482074Z","steps":["trace[1494024152] 'process raft request' (duration: 168.295961ms)","trace[1494024152] 'compare' (duration: 17.708935ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:16.172818Z","caller":"traceutil/trace.go:171","msg":"trace[215376620] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"438.033954ms","start":"2026-08-20T01:14:15.734758Z","end":"2026-08-20T01:14:16.172792Z","steps":["trace[215376620] 'process raft request' (duration: 437.888511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:16.173024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:15.734742Z","time spent":"438.220098ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":761,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:14:16.416181Z","caller":"traceutil/trace.go:171","msg":"trace[140115913] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"666.751982ms","start":"2026-08-20T01:14:15.749404Z","end":"2026-08-20T01:14:16.416156Z","steps":["trace[140115913] 'process raft request' (duration: 666.603349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:16.416346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:15.749375Z","time spent":"666.879805ms","remote":"127.0.0.1:45170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18501,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:16.989075Z","caller":"traceutil/trace.go:171","msg":"trace[2049265746] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:695; }","duration":"131.004668ms","start":"2026-08-20T01:14:16.858055Z","end":"2026-08-20T01:14:16.989059Z","steps":["trace[2049265746] 'read index received' (duration: 120.316376ms)","trace[2049265746] 'applied index is now lower than readState.Index' (duration: 10.687632ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:16.989118Z","caller":"traceutil/trace.go:171","msg":"trace[787386672] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"169.0942ms","start":"2026-08-20T01:14:16.819997Z","end":"2026-08-20T01:14:16.989092Z","steps":["trace[787386672] 'process raft request' (duration: 158.410868ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:16.989188Z","caller":"traceutil/trace.go:171","msg":"trace[1383536987] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"162.26648ms","start":"2026-08-20T01:14:16.826877Z","end":"2026-08-20T01:14:16.989144Z","steps":["trace[1383536987] 'process raft request' (duration: 162.145648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:16.989276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.216373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-rk9pr\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-08-20T01:14:16.989338Z","caller":"traceutil/trace.go:171","msg":"trace[1583714712] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-rk9pr; range_end:; response_count:1; response_revision:674; }","duration":"131.286774ms","start":"2026-08-20T01:14:16.858039Z","end":"2026-08-20T01:14:16.989326Z","steps":["trace[1583714712] 'agreement among raft nodes before linearized reading' (duration: 131.089201ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:17.182559Z","caller":"traceutil/trace.go:171","msg":"trace[574685456] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"184.31026ms","start":"2026-08-20T01:14:16.998225Z","end":"2026-08-20T01:14:17.182535Z","steps":["trace[574685456] 'process raft request' (duration: 183.27341ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:17.204058Z","caller":"traceutil/trace.go:171","msg":"trace[1908760972] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"185.928981ms","start":"2026-08-20T01:14:17.018105Z","end":"2026-08-20T01:14:17.204034Z","steps":["trace[1908760972] 'process raft request' (duration: 185.862229ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:17.20407Z","caller":"traceutil/trace.go:171","msg":"trace[1327750521] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"205.618994ms","start":"2026-08-20T01:14:16.998423Z","end":"2026-08-20T01:14:17.204042Z","steps":["trace[1327750521] 'process raft request' (duration: 205.43001ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:17.421384Z","caller":"traceutil/trace.go:171","msg":"trace[2086516141] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"212.651977ms","start":"2026-08-20T01:14:17.208712Z","end":"2026-08-20T01:14:17.421364Z","steps":["trace[2086516141] 'process raft request' (duration: 211.452734ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:17.421578Z","caller":"traceutil/trace.go:171","msg":"trace[218675806] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:700; }","duration":"204.691996ms","start":"2026-08-20T01:14:17.216858Z","end":"2026-08-20T01:14:17.42155Z","steps":["trace[218675806] 'read index received' (duration: 16.689117ms)","trace[218675806] 'applied index is now lower than readState.Index' (duration: 188.001229ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:17.421616Z","caller":"traceutil/trace.go:171","msg":"trace[969612025] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"209.528467ms","start":"2026-08-20T01:14:17.212074Z","end":"2026-08-20T01:14:17.421603Z","steps":["trace[969612025] 'process raft request' (duration: 209.231721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:17.421689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.818478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:17.421737Z","caller":"traceutil/trace.go:171","msg":"trace[266478146] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:680; }","duration":"204.894469ms","start":"2026-08-20T01:14:17.216824Z","end":"2026-08-20T01:14:17.421719Z","steps":["trace[266478146] 'agreement among raft nodes before linearized reading' (duration: 204.821388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:17.922363Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529671370480598994,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-20T01:14:18.053443Z","caller":"traceutil/trace.go:171","msg":"trace[1455472624] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"633.908804ms","start":"2026-08-20T01:14:17.419507Z","end":"2026-08-20T01:14:18.053416Z","steps":["trace[1455472624] 'process raft request' (duration: 595.608268ms)","trace[1455472624] 'compare' (duration: 37.897908ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:18.053583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:17.419483Z","time spent":"634.029326ms","remote":"127.0.0.1:44996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:14:18.158065Z","caller":"traceutil/trace.go:171","msg":"trace[127914441] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"730.311181ms","start":"2026-08-20T01:14:17.427733Z","end":"2026-08-20T01:14:18.158044Z","steps":["trace[127914441] 'process raft request' (duration: 728.504296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:18.158219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:17.427714Z","time spent":"730.423602ms","remote":"127.0.0.1:44996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:18.158464Z","caller":"traceutil/trace.go:171","msg":"trace[2146691639] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"729.75655ms","start":"2026-08-20T01:14:17.428673Z","end":"2026-08-20T01:14:18.15843Z","steps":["trace[2146691639] 'process raft request' (duration: 729.292801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:18.158621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:17.428667Z","time spent":"729.865622ms","remote":"127.0.0.1:45440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:18.158706Z","caller":"traceutil/trace.go:171","msg":"trace[1690581134] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"334.821952ms","start":"2026-08-20T01:14:17.82387Z","end":"2026-08-20T01:14:18.158692Z","steps":["trace[1690581134] 'process raft request' (duration: 334.397654ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:18.158713Z","caller":"traceutil/trace.go:171","msg":"trace[2077873206] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:703; }","duration":"737.033508ms","start":"2026-08-20T01:14:17.421654Z","end":"2026-08-20T01:14:18.158688Z","steps":["trace[2077873206] 'read index received' (duration: 593.527669ms)","trace[2077873206] 'applied index is now lower than readState.Index' (duration: 143.504599ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:18.158747Z","caller":"traceutil/trace.go:171","msg":"trace[447222827] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"280.489343ms","start":"2026-08-20T01:14:17.878241Z","end":"2026-08-20T01:14:18.15873Z","steps":["trace[447222827] 'process raft request' (duration: 280.34409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:18.158781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:17.823846Z","time spent":"334.903643ms","remote":"127.0.0.1:44996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T01:14:18.158868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.247686ms","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-20T01:14:18.158991Z","caller":"traceutil/trace.go:171","msg":"trace[1469299300] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:685; }","duration":"799.412799ms","start":"2026-08-20T01:14:17.359565Z","end":"2026-08-20T01:14:18.158978Z","steps":["trace[1469299300] 'agreement among raft nodes before linearized reading' (duration: 799.223976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:18.159028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:17.359547Z","time spent":"799.47143ms","remote":"127.0.0.1:45090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-20T01:14:18.159114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.491598ms","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-20T01:14:18.159179Z","caller":"traceutil/trace.go:171","msg":"trace[1679379592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:685; }","duration":"821.590089ms","start":"2026-08-20T01:14:17.337574Z","end":"2026-08-20T01:14:18.159164Z","steps":["trace[1679379592] 'agreement among raft nodes before linearized reading' (duration: 821.405735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:18.159219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:17.337555Z","time spent":"821.6547ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-20T01:14:18.528686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.366924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:14:18.528926Z","caller":"traceutil/trace.go:171","msg":"trace[290081171] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"365.578877ms","start":"2026-08-20T01:14:18.163327Z","end":"2026-08-20T01:14:18.528906Z","steps":["trace[290081171] 'process raft request' (duration: 365.489345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:18.528996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:18.163305Z","time spent":"365.663809ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:18.528966Z","caller":"traceutil/trace.go:171","msg":"trace[706264037] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"370.169714ms","start":"2026-08-20T01:14:18.158764Z","end":"2026-08-20T01:14:18.528934Z","steps":["trace[706264037] 'read index received' (duration: 176.436544ms)","trace[706264037] 'applied index is now lower than readState.Index' (duration: 193.73034ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:18.529208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.776123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:18.529201Z","caller":"traceutil/trace.go:171","msg":"trace[1785251908] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"471.004855ms","start":"2026-08-20T01:14:18.05817Z","end":"2026-08-20T01:14:18.529175Z","steps":["trace[1785251908] 'process raft request' (duration: 277.016379ms)","trace[1785251908] 'compare' (duration: 193.18941ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:18.529355Z","caller":"traceutil/trace.go:171","msg":"trace[1823953869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"910.918495ms","start":"2026-08-20T01:14:17.618385Z","end":"2026-08-20T01:14:18.529303Z","steps":["trace[1823953869] 'agreement among raft nodes before linearized reading' (duration: 910.65651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:18.529407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042061008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-20T01:14:18.529458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.554693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T01:14:18.529435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:17.618378Z","time spent":"911.045297ms","remote":"127.0.0.1:44952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-20T01:14:18.529313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.423491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T01:14:18.529494Z","caller":"traceutil/trace.go:171","msg":"trace[185546529] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:687; }","duration":"511.676475ms","start":"2026-08-20T01:14:18.017805Z","end":"2026-08-20T01:14:18.529481Z","steps":["trace[185546529] 'agreement among raft nodes before linearized reading' (duration: 511.569653ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:18.529505Z","caller":"traceutil/trace.go:171","msg":"trace[405516524] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:687; }","duration":"358.676956ms","start":"2026-08-20T01:14:18.170819Z","end":"2026-08-20T01:14:18.529496Z","steps":["trace[405516524] 'agreement among raft nodes before linearized reading' (duration: 358.427991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:18.52952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:18.017782Z","time spent":"511.732576ms","remote":"127.0.0.1:44996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-08-20T01:14:18.529562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:18.170806Z","time spent":"358.725926ms","remote":"127.0.0.1:45462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-08-20T01:14:18.529547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:18.05814Z","time spent":"471.179317ms","remote":"127.0.0.1:44996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:18.529584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.883989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-08-20T01:14:18.529578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042619169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-20T01:14:18.529635Z","caller":"traceutil/trace.go:171","msg":"trace[514007407] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:687; }","duration":"111.963341ms","start":"2026-08-20T01:14:18.417651Z","end":"2026-08-20T01:14:18.529614Z","steps":["trace[514007407] 'agreement among raft nodes before linearized reading' (duration: 111.794198ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:18.529466Z","caller":"traceutil/trace.go:171","msg":"trace[234187857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"1.04215562s","start":"2026-08-20T01:14:17.4873Z","end":"2026-08-20T01:14:18.529455Z","steps":["trace[234187857] 'agreement among raft nodes before linearized reading' (duration: 1.041998657s)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:18.529648Z","caller":"traceutil/trace.go:171","msg":"trace[2080469090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"1.04273623s","start":"2026-08-20T01:14:17.486895Z","end":"2026-08-20T01:14:18.529631Z","steps":["trace[2080469090] 'agreement among raft nodes before linearized reading' (duration: 1.042523596s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:18.529685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:17.487278Z","time spent":"1.042395134s","remote":"127.0.0.1:45256","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-20T01:14:18.529689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:17.486876Z","time spent":"1.042804722s","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-20T01:14:18.529701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042785422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T01:14:18.529799Z","caller":"traceutil/trace.go:171","msg":"trace[1824244004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"1.042927134s","start":"2026-08-20T01:14:17.486852Z","end":"2026-08-20T01:14:18.529779Z","steps":["trace[1824244004] 'agreement among raft nodes before linearized reading' (duration: 1.04217198s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:18.529854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:17.486831Z","time spent":"1.043010766s","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-20T01:14:18.529266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.309182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T01:14:18.529888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.745376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:18.529918Z","caller":"traceutil/trace.go:171","msg":"trace[926891762] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:687; }","duration":"911.998865ms","start":"2026-08-20T01:14:17.617899Z","end":"2026-08-20T01:14:18.529898Z","steps":["trace[926891762] 'agreement among raft nodes before linearized reading' (duration: 911.321713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:18.529985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:17.61788Z","time spent":"912.092247ms","remote":"127.0.0.1:44996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-08-20T01:14:18.529931Z","caller":"traceutil/trace.go:171","msg":"trace[251052769] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:687; }","duration":"312.822798ms","start":"2026-08-20T01:14:18.217092Z","end":"2026-08-20T01:14:18.529915Z","steps":["trace[251052769] 'agreement among raft nodes before linearized reading' (duration: 312.746046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:18.530072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:18.217075Z","time spent":"312.98249ms","remote":"127.0.0.1:44996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-08-20T01:14:19.256578Z","caller":"traceutil/trace.go:171","msg":"trace[118544247] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"135.135602ms","start":"2026-08-20T01:14:19.121395Z","end":"2026-08-20T01:14:19.256531Z","steps":["trace[118544247] 'process raft request' (duration: 134.887518ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:20.028676Z","caller":"traceutil/trace.go:171","msg":"trace[830913080] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:735; }","duration":"151.35594ms","start":"2026-08-20T01:14:19.877296Z","end":"2026-08-20T01:14:20.028652Z","steps":["trace[830913080] 'read index received' (duration: 151.025894ms)","trace[830913080] 'applied index is now lower than readState.Index' (duration: 329.096µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:20.02873Z","caller":"traceutil/trace.go:171","msg":"trace[190573306] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"179.224249ms","start":"2026-08-20T01:14:19.849466Z","end":"2026-08-20T01:14:20.02869Z","steps":["trace[190573306] 'process raft request' (duration: 179.033445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:20.028885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.572274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-98tj6.18cd5e6efa522b2b\" ","response":"range_response_count:1 size:795"} {"level":"info","ts":"2026-08-20T01:14:20.02899Z","caller":"traceutil/trace.go:171","msg":"trace[1750541900] range","detail":"{range_begin:/registry/events/kube-system/cilium-98tj6.18cd5e6efa522b2b; range_end:; response_count:1; response_revision:712; }","duration":"151.721907ms","start":"2026-08-20T01:14:19.877257Z","end":"2026-08-20T01:14:20.028979Z","steps":["trace[1750541900] 'agreement among raft nodes before linearized reading' (duration: 151.486863ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:20.285341Z","caller":"traceutil/trace.go:171","msg":"trace[1113157154] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"156.771604ms","start":"2026-08-20T01:14:20.128543Z","end":"2026-08-20T01:14:20.285314Z","steps":["trace[1113157154] 'process raft request' (duration: 156.502569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:29.674204Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.070095104s","expected-duration":"1s"} {"level":"info","ts":"2026-08-20T01:14:29.744582Z","caller":"traceutil/trace.go:171","msg":"trace[1234501630] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:802; }","duration":"1.151363114s","start":"2026-08-20T01:14:28.593193Z","end":"2026-08-20T01:14:29.744556Z","steps":["trace[1234501630] 'read index received' (duration: 10.965759ms)","trace[1234501630] 'applied index is now lower than readState.Index' (duration: 1.140396015s)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:29.744597Z","caller":"traceutil/trace.go:171","msg":"trace[865465377] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"1.164966274s","start":"2026-08-20T01:14:28.579597Z","end":"2026-08-20T01:14:29.744563Z","steps":["trace[865465377] 'process raft request' (duration: 1.094816556s)","trace[865465377] 'compare' (duration: 69.690829ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:29.744776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:28.579576Z","time spent":"1.165100216s","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:29.744855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151636669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-20T01:14:29.74489Z","caller":"traceutil/trace.go:171","msg":"trace[1083727448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"1.151716991s","start":"2026-08-20T01:14:28.593164Z","end":"2026-08-20T01:14:29.744881Z","steps":["trace[1083727448] 'agreement among raft nodes before linearized reading' (duration: 1.151534457s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:29.744919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:28.593154Z","time spent":"1.151758741s","remote":"127.0.0.1:45256","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-20T01:14:29.745092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15009817s","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-20T01:14:29.746114Z","caller":"traceutil/trace.go:171","msg":"trace[1798648522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"1.151253852s","start":"2026-08-20T01:14:28.594824Z","end":"2026-08-20T01:14:29.746077Z","steps":["trace[1798648522] 'agreement among raft nodes before linearized reading' (duration: 1.149978267s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:29.745184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.36317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rs92l\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-20T01:14:29.74623Z","caller":"traceutil/trace.go:171","msg":"trace[956001581] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rs92l; range_end:; response_count:1; response_revision:777; }","duration":"952.424351ms","start":"2026-08-20T01:14:28.793774Z","end":"2026-08-20T01:14:29.746198Z","steps":["trace[956001581] 'agreement among raft nodes before linearized reading' (duration: 951.295029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:29.745196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.178347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-20T01:14:29.746329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:28.793726Z","time spent":"952.569023ms","remote":"127.0.0.1:45170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rs92l\" "} {"level":"info","ts":"2026-08-20T01:14:29.746323Z","caller":"traceutil/trace.go:171","msg":"trace[899734848] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:777; }","duration":"149.363089ms","start":"2026-08-20T01:14:29.596944Z","end":"2026-08-20T01:14:29.746307Z","steps":["trace[899734848] 'agreement among raft nodes before linearized reading' (duration: 148.129345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:29.745722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125385039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:29.746561Z","caller":"traceutil/trace.go:171","msg":"trace[1446744588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"1.126693813s","start":"2026-08-20T01:14:28.619829Z","end":"2026-08-20T01:14:29.746523Z","steps":["trace[1446744588] 'agreement among raft nodes before linearized reading' (duration: 1.125384058s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:29.745349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000366274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T01:14:29.746728Z","caller":"traceutil/trace.go:171","msg":"trace[1888763887] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:777; }","duration":"1.001791052s","start":"2026-08-20T01:14:28.744927Z","end":"2026-08-20T01:14:29.746718Z","steps":["trace[1888763887] 'agreement among raft nodes before linearized reading' (duration: 1.000264303s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:29.746759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:28.744907Z","time spent":"1.001842962s","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-20T01:14:29.746992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:28.619805Z","time spent":"1.127152352s","remote":"127.0.0.1:44952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-20T01:14:29.746236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:28.594807Z","time spent":"1.151376885s","remote":"127.0.0.1:45256","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-20T01:14:33.990048Z","caller":"traceutil/trace.go:171","msg":"trace[674080455] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"135.713436ms","start":"2026-08-20T01:14:33.85431Z","end":"2026-08-20T01:14:33.990023Z","steps":["trace[674080455] 'process raft request' (duration: 135.575013ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:34.478799Z","caller":"traceutil/trace.go:171","msg":"trace[1721579402] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"622.995269ms","start":"2026-08-20T01:14:33.855782Z","end":"2026-08-20T01:14:34.478777Z","steps":["trace[1721579402] 'process raft request' (duration: 622.784765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:34.479301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:33.855756Z","time spent":"623.454538ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:34.479499Z","caller":"traceutil/trace.go:171","msg":"trace[1020532070] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"623.518909ms","start":"2026-08-20T01:14:33.855896Z","end":"2026-08-20T01:14:34.479415Z","steps":["trace[1020532070] 'process raft request' (duration: 622.838416ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:34.479552Z","caller":"traceutil/trace.go:171","msg":"trace[517702946] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"623.5855ms","start":"2026-08-20T01:14:33.855937Z","end":"2026-08-20T01:14:34.479523Z","steps":["trace[517702946] 'process raft request' (duration: 623.330595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:34.479701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:33.855931Z","time spent":"623.689462ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:34.479722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:33.855886Z","time spent":"623.724953ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:34.479753Z","caller":"traceutil/trace.go:171","msg":"trace[1794231526] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:860; }","duration":"281.953475ms","start":"2026-08-20T01:14:34.197777Z","end":"2026-08-20T01:14:34.47973Z","steps":["trace[1794231526] 'read index received' (duration: 280.761632ms)","trace[1794231526] 'applied index is now lower than readState.Index' (duration: 1.190813ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:34.479845Z","caller":"traceutil/trace.go:171","msg":"trace[158044433] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"620.795767ms","start":"2026-08-20T01:14:33.85904Z","end":"2026-08-20T01:14:34.479835Z","steps":["trace[158044433] 'process raft request' (duration: 620.569753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:34.479861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.071677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T01:14:34.479902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:33.859014Z","time spent":"620.859628ms","remote":"127.0.0.1:45170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5662,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:34.479909Z","caller":"traceutil/trace.go:171","msg":"trace[675382611] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:0; response_revision:836; }","duration":"282.171589ms","start":"2026-08-20T01:14:34.197721Z","end":"2026-08-20T01:14:34.479892Z","steps":["trace[675382611] 'agreement among raft nodes before linearized reading' (duration: 282.094998ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:34.88146Z","caller":"traceutil/trace.go:171","msg":"trace[458176158] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"395.376805ms","start":"2026-08-20T01:14:34.486056Z","end":"2026-08-20T01:14:34.881433Z","steps":["trace[458176158] 'process raft request' (duration: 339.72976ms)","trace[458176158] 'compare' (duration: 55.526673ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:34.881628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:34.486029Z","time spent":"395.509088ms","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1105,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:34.903735Z","caller":"traceutil/trace.go:171","msg":"trace[1907829133] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:864; }","duration":"275.125434ms","start":"2026-08-20T01:14:34.628584Z","end":"2026-08-20T01:14:34.90371Z","steps":["trace[1907829133] 'read index received' (duration: 197.215034ms)","trace[1907829133] 'applied index is now lower than readState.Index' (duration: 77.90934ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:34.903858Z","caller":"traceutil/trace.go:171","msg":"trace[1272817833] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"417.747073ms","start":"2026-08-20T01:14:34.486059Z","end":"2026-08-20T01:14:34.903806Z","steps":["trace[1272817833] 'process raft request' (duration: 417.308925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:34.904049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.44263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-20T01:14:34.903878Z","caller":"traceutil/trace.go:171","msg":"trace[331300287] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"417.680962ms","start":"2026-08-20T01:14:34.486132Z","end":"2026-08-20T01:14:34.903813Z","steps":["trace[331300287] 'process raft request' (duration: 417.405597ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:34.904123Z","caller":"traceutil/trace.go:171","msg":"trace[673840359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:839; }","duration":"275.560263ms","start":"2026-08-20T01:14:34.628543Z","end":"2026-08-20T01:14:34.904103Z","steps":["trace[673840359] 'agreement among raft nodes before linearized reading' (duration: 275.286708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:34.904055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:34.486031Z","time spent":"417.930057ms","remote":"127.0.0.1:47396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":553,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T01:14:34.904386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:34.486029Z","time spent":"418.09711ms","remote":"127.0.0.1:45262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:35.239537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.537893ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-20T01:14:35.239813Z","caller":"traceutil/trace.go:171","msg":"trace[1616984901] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:867; }","duration":"335.92321ms","start":"2026-08-20T01:14:34.903813Z","end":"2026-08-20T01:14:35.239737Z","steps":["trace[1616984901] 'read index received' (duration: 35.215854ms)","trace[1616984901] 'applied index is now lower than readState.Index' (duration: 300.702126ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:35.239945Z","caller":"traceutil/trace.go:171","msg":"trace[966021259] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"328.795564ms","start":"2026-08-20T01:14:34.911128Z","end":"2026-08-20T01:14:35.239924Z","steps":["trace[966021259] 'process raft request' (duration: 328.677482ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:35.240018Z","caller":"traceutil/trace.go:171","msg":"trace[847302076] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"330.663199ms","start":"2026-08-20T01:14:34.909336Z","end":"2026-08-20T01:14:35.239999Z","steps":["trace[847302076] 'process raft request' (duration: 330.309622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:35.240071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:34.911104Z","time spent":"328.891165ms","remote":"127.0.0.1:47396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1071,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:35.240221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:34.909313Z","time spent":"330.767091ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:35.239995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.742669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:35.240548Z","caller":"traceutil/trace.go:171","msg":"trace[1300271341] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:841; }","duration":"530.35102ms","start":"2026-08-20T01:14:34.710178Z","end":"2026-08-20T01:14:35.240529Z","steps":["trace[1300271341] 'agreement among raft nodes before linearized reading' (duration: 529.635647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:35.240603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:34.710153Z","time spent":"530.438072ms","remote":"127.0.0.1:45146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-08-20T01:14:43.134143Z","caller":"traceutil/trace.go:171","msg":"trace[475605500] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"147.464984ms","start":"2026-08-20T01:14:42.986641Z","end":"2026-08-20T01:14:43.134106Z","steps":["trace[475605500] 'process raft request' (duration: 134.569476ms)","trace[475605500] 'compare' (duration: 12.731725ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:43.159741Z","caller":"traceutil/trace.go:171","msg":"trace[754749684] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"135.142517ms","start":"2026-08-20T01:14:43.024566Z","end":"2026-08-20T01:14:43.159709Z","steps":["trace[754749684] 'process raft request' (duration: 135.071176ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:43.160257Z","caller":"traceutil/trace.go:171","msg":"trace[617484656] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"172.425294ms","start":"2026-08-20T01:14:42.987786Z","end":"2026-08-20T01:14:43.160211Z","steps":["trace[617484656] 'process raft request' (duration: 171.780151ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:43.160322Z","caller":"traceutil/trace.go:171","msg":"trace[1991035173] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"173.521975ms","start":"2026-08-20T01:14:42.98675Z","end":"2026-08-20T01:14:43.160272Z","steps":["trace[1991035173] 'process raft request' (duration: 172.684749ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:44.127996Z","caller":"traceutil/trace.go:171","msg":"trace[912564135] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"102.648503ms","start":"2026-08-20T01:14:44.025309Z","end":"2026-08-20T01:14:44.127958Z","steps":["trace[912564135] 'process raft request' (duration: 102.459139ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:50.386271Z","caller":"traceutil/trace.go:171","msg":"trace[107746165] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"148.38167ms","start":"2026-08-20T01:14:50.237861Z","end":"2026-08-20T01:14:50.386243Z","steps":["trace[107746165] 'process raft request' (duration: 148.239588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:55.051718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.04553ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-20T01:14:56.790665Z","caller":"traceutil/trace.go:171","msg":"trace[1019514813] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"170.772849ms","start":"2026-08-20T01:14:56.61987Z","end":"2026-08-20T01:14:56.790643Z","steps":["trace[1019514813] 'read index received' (duration: 170.563395ms)","trace[1019514813] 'applied index is now lower than readState.Index' (duration: 208.564µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:56.790746Z","caller":"traceutil/trace.go:171","msg":"trace[1013436260] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"367.043202ms","start":"2026-08-20T01:14:56.423681Z","end":"2026-08-20T01:14:56.790724Z","steps":["trace[1013436260] 'process raft request' (duration: 366.809387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:56.790873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.836354ms","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-20T01:14:56.790909Z","caller":"traceutil/trace.go:171","msg":"trace[21909216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:997; }","duration":"136.914345ms","start":"2026-08-20T01:14:56.653985Z","end":"2026-08-20T01:14:56.7909Z","steps":["trace[21909216] 'agreement among raft nodes before linearized reading' (duration: 136.788103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:56.790917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:56.423655Z","time spent":"367.140114ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:56.791072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.354317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-20T01:14:56.79121Z","caller":"traceutil/trace.go:171","msg":"trace[1611120339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:997; }","duration":"103.545801ms","start":"2026-08-20T01:14:56.687638Z","end":"2026-08-20T01:14:56.791184Z","steps":["trace[1611120339] 'agreement among raft nodes before linearized reading' (duration: 103.167404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:56.791181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.990424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:56.791361Z","caller":"traceutil/trace.go:171","msg":"trace[819259262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"171.460853ms","start":"2026-08-20T01:14:56.619824Z","end":"2026-08-20T01:14:56.791285Z","steps":["trace[819259262] 'agreement among raft nodes before linearized reading' (duration: 170.915712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:56.791476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.314065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T01:14:56.791587Z","caller":"traceutil/trace.go:171","msg":"trace[121656887] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:997; }","duration":"105.503648ms","start":"2026-08-20T01:14:56.686063Z","end":"2026-08-20T01:14:56.791567Z","steps":["trace[121656887] 'agreement among raft nodes before linearized reading' (duration: 104.806645ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:58.96348Z","caller":"traceutil/trace.go:171","msg":"trace[1538429972] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"125.436156ms","start":"2026-08-20T01:14:58.838019Z","end":"2026-08-20T01:14:58.963455Z","steps":["trace[1538429972] 'read index received' (duration: 125.262202ms)","trace[1538429972] 'applied index is now lower than readState.Index' (duration: 172.574µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:58.963592Z","caller":"traceutil/trace.go:171","msg":"trace[122543058] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"163.254588ms","start":"2026-08-20T01:14:58.800305Z","end":"2026-08-20T01:14:58.96356Z","steps":["trace[122543058] 'process raft request' (duration: 162.982312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:58.963748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.706951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-20T01:14:58.963768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.693561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-20T01:14:58.9638Z","caller":"traceutil/trace.go:171","msg":"trace[660727990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1003; }","duration":"125.814483ms","start":"2026-08-20T01:14:58.837973Z","end":"2026-08-20T01:14:58.963787Z","steps":["trace[660727990] 'agreement among raft nodes before linearized reading' (duration: 125.592059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:58.963813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.297144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T01:14:58.963832Z","caller":"traceutil/trace.go:171","msg":"trace[1964302388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1003; }","duration":"124.798554ms","start":"2026-08-20T01:14:58.83902Z","end":"2026-08-20T01:14:58.963818Z","steps":["trace[1964302388] 'agreement among raft nodes before linearized reading' (duration: 124.60336ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:58.963877Z","caller":"traceutil/trace.go:171","msg":"trace[601710013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"124.385775ms","start":"2026-08-20T01:14:58.839468Z","end":"2026-08-20T01:14:58.963854Z","steps":["trace[601710013] 'agreement among raft nodes before linearized reading' (duration: 124.177371ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:01.185644Z","caller":"traceutil/trace.go:171","msg":"trace[858042339] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"129.367955ms","start":"2026-08-20T01:15:01.056254Z","end":"2026-08-20T01:15:01.185622Z","steps":["trace[858042339] 'process raft request' (duration: 129.329474ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:01.185762Z","caller":"traceutil/trace.go:171","msg":"trace[163068505] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"130.498306ms","start":"2026-08-20T01:15:01.055196Z","end":"2026-08-20T01:15:01.185694Z","steps":["trace[163068505] 'process raft request' (duration: 87.386402ms)","trace[163068505] 'compare' (duration: 42.762137ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:15:01.186015Z","caller":"traceutil/trace.go:171","msg":"trace[1758185571] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"130.16114ms","start":"2026-08-20T01:15:01.055835Z","end":"2026-08-20T01:15:01.185996Z","steps":["trace[1758185571] 'process raft request' (duration: 129.705481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:01.422672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.148787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T01:15:01.422757Z","caller":"traceutil/trace.go:171","msg":"trace[2010457684] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1014; }","duration":"119.809149ms","start":"2026-08-20T01:15:01.302931Z","end":"2026-08-20T01:15:01.42274Z","steps":["trace[2010457684] 'count revisions from in-memory index tree' (duration: 119.070915ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:02.30226Z","caller":"traceutil/trace.go:171","msg":"trace[59625880] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"209.264073ms","start":"2026-08-20T01:15:02.092972Z","end":"2026-08-20T01:15:02.302236Z","steps":["trace[59625880] 'process raft request' (duration: 156.371289ms)","trace[59625880] 'compare' (duration: 52.803512ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:15:03.759489Z","caller":"traceutil/trace.go:171","msg":"trace[2046969323] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"103.880032ms","start":"2026-08-20T01:15:03.655586Z","end":"2026-08-20T01:15:03.759466Z","steps":["trace[2046969323] 'process raft request' (duration: 32.5179ms)","trace[2046969323] 'compare' (duration: 71.25724ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:11.713093Z","caller":"traceutil/trace.go:171","msg":"trace[1677507000] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"150.609318ms","start":"2026-08-20T01:16:11.56245Z","end":"2026-08-20T01:16:11.71306Z","steps":["trace[1677507000] 'process raft request' (duration: 150.426495ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:11.848351Z","caller":"traceutil/trace.go:171","msg":"trace[626272169] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1308; }","duration":"122.347593ms","start":"2026-08-20T01:16:11.725978Z","end":"2026-08-20T01:16:11.848326Z","steps":["trace[626272169] 'read index received' (duration: 56.696884ms)","trace[626272169] 'applied index is now lower than readState.Index' (duration: 65.645239ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:11.8485Z","caller":"traceutil/trace.go:171","msg":"trace[247642716] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"130.396811ms","start":"2026-08-20T01:16:11.718059Z","end":"2026-08-20T01:16:11.848455Z","steps":["trace[247642716] 'process raft request' (duration: 130.183806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:11.848704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.70975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/test-pvc.18cd5e8907d106e9\" ","response":"range_response_count:1 size:916"} {"level":"info","ts":"2026-08-20T01:16:11.848752Z","caller":"traceutil/trace.go:171","msg":"trace[1937334110] range","detail":"{range_begin:/registry/events/default/test-pvc.18cd5e8907d106e9; range_end:; response_count:1; response_revision:1260; }","duration":"122.796232ms","start":"2026-08-20T01:16:11.725942Z","end":"2026-08-20T01:16:11.848738Z","steps":["trace[1937334110] 'agreement among raft nodes before linearized reading' (duration: 122.481586ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:11.848361Z","caller":"traceutil/trace.go:171","msg":"trace[1026930083] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"130.647436ms","start":"2026-08-20T01:16:11.717686Z","end":"2026-08-20T01:16:11.848334Z","steps":["trace[1026930083] 'process raft request' (duration: 64.975936ms)","trace[1026930083] 'compare' (duration: 65.363594ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:12.343943Z","caller":"traceutil/trace.go:171","msg":"trace[1463093162] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"156.708078ms","start":"2026-08-20T01:16:12.187212Z","end":"2026-08-20T01:16:12.34392Z","steps":["trace[1463093162] 'process raft request' (duration: 156.519465ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:12.381716Z","caller":"traceutil/trace.go:171","msg":"trace[604364686] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"161.284889ms","start":"2026-08-20T01:16:12.220411Z","end":"2026-08-20T01:16:12.381696Z","steps":["trace[604364686] 'process raft request' (duration: 161.096895ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:20.140936Z","caller":"traceutil/trace.go:171","msg":"trace[2008833640] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1355; }","duration":"365.787861ms","start":"2026-08-20T01:16:19.77513Z","end":"2026-08-20T01:16:20.140918Z","steps":["trace[2008833640] 'read index received' (duration: 365.595355ms)","trace[2008833640] 'applied index is now lower than readState.Index' (duration: 191.826µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:20.141026Z","caller":"traceutil/trace.go:171","msg":"trace[1236858250] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"388.3347ms","start":"2026-08-20T01:16:19.75266Z","end":"2026-08-20T01:16:20.140995Z","steps":["trace[1236858250] 'process raft request' (duration: 388.140394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:20.141129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.985137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T01:16:20.141159Z","caller":"traceutil/trace.go:171","msg":"trace[1738488536] range","detail":"{range_begin:/registry/masterleases/199.19.213.222; range_end:; response_count:1; response_revision:1304; }","duration":"366.068389ms","start":"2026-08-20T01:16:19.775082Z","end":"2026-08-20T01:16:20.141151Z","steps":["trace[1738488536] 'agreement among raft nodes before linearized reading' (duration: 365.949076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:20.141188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:19.775071Z","time spent":"366.108201ms","remote":"127.0.0.1:44982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.222\" "} {"level":"warn","ts":"2026-08-20T01:16:20.141181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:19.752642Z","time spent":"388.455154ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:16:20.141356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.998447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T01:16:20.141407Z","caller":"traceutil/trace.go:171","msg":"trace[1234592812] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1304; }","duration":"315.07349ms","start":"2026-08-20T01:16:19.826307Z","end":"2026-08-20T01:16:20.141381Z","steps":["trace[1234592812] 'agreement among raft nodes before linearized reading' (duration: 315.007958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:20.141446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:19.826286Z","time spent":"315.149132ms","remote":"127.0.0.1:45308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-20T01:16:20.141618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.918888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T01:16:20.141645Z","caller":"traceutil/trace.go:171","msg":"trace[1240911615] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1304; }","duration":"194.99774ms","start":"2026-08-20T01:16:19.946638Z","end":"2026-08-20T01:16:20.141636Z","steps":["trace[1240911615] 'agreement among raft nodes before linearized reading' (duration: 194.925788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:20.141639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.093779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-20T01:16:20.141721Z","caller":"traceutil/trace.go:171","msg":"trace[1306164140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1304; }","duration":"190.205131ms","start":"2026-08-20T01:16:19.951499Z","end":"2026-08-20T01:16:20.141704Z","steps":["trace[1306164140] 'agreement among raft nodes before linearized reading' (duration: 190.018806ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:24.809074Z","caller":"traceutil/trace.go:171","msg":"trace[1506426434] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"101.265288ms","start":"2026-08-20T01:16:24.70778Z","end":"2026-08-20T01:16:24.809045Z","steps":["trace[1506426434] 'read index received' (duration: 100.925378ms)","trace[1506426434] 'applied index is now lower than readState.Index' (duration: 338.62µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:24.809115Z","caller":"traceutil/trace.go:171","msg":"trace[2068349921] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"117.672571ms","start":"2026-08-20T01:16:24.691412Z","end":"2026-08-20T01:16:24.809084Z","steps":["trace[2068349921] 'process raft request' (duration: 117.429373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:24.809304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.495325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2569"} {"level":"info","ts":"2026-08-20T01:16:24.809348Z","caller":"traceutil/trace.go:171","msg":"trace[1989319123] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1328; }","duration":"101.609299ms","start":"2026-08-20T01:16:24.707725Z","end":"2026-08-20T01:16:24.809334Z","steps":["trace[1989319123] 'agreement among raft nodes before linearized reading' (duration: 101.427314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:27.120363Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529671370480600561,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T01:16:27.545723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142842718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-20T01:16:27.545859Z","caller":"traceutil/trace.go:171","msg":"trace[1249266509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1334; }","duration":"1.143023073s","start":"2026-08-20T01:16:26.402812Z","end":"2026-08-20T01:16:27.545835Z","steps":["trace[1249266509] 'range keys from in-memory index tree' (duration: 1.14257338s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:27.545917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:26.402792Z","time spent":"1.143116336s","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-20T01:16:27.585605Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.159361168s","expected-duration":"1s"} {"level":"info","ts":"2026-08-20T01:16:27.586037Z","caller":"traceutil/trace.go:171","msg":"trace[1137459613] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"966.171115ms","start":"2026-08-20T01:16:26.619834Z","end":"2026-08-20T01:16:27.586006Z","steps":["trace[1137459613] 'read index received' (duration: 966.048162ms)","trace[1137459613] 'applied index is now lower than readState.Index' (duration: 121.183µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:16:27.586309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.442493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:16:27.586366Z","caller":"traceutil/trace.go:171","msg":"trace[1146715558] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"1.160272376s","start":"2026-08-20T01:16:26.42607Z","end":"2026-08-20T01:16:27.586342Z","steps":["trace[1146715558] 'process raft request' (duration: 1.15973486s)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:27.586395Z","caller":"traceutil/trace.go:171","msg":"trace[584472061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"966.585079ms","start":"2026-08-20T01:16:26.619793Z","end":"2026-08-20T01:16:27.586378Z","steps":["trace[584472061] 'agreement among raft nodes before linearized reading' (duration: 966.393963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:27.586447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:26.619773Z","time spent":"966.65284ms","remote":"127.0.0.1:44952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-20T01:16:27.586468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:26.426033Z","time spent":"1.16037919s","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:16:30.409733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.813851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:16:30.409884Z","caller":"traceutil/trace.go:171","msg":"trace[874510460] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"151.802119ms","start":"2026-08-20T01:16:30.258056Z","end":"2026-08-20T01:16:30.409858Z","steps":["trace[874510460] 'process raft request' (duration: 48.748391ms)","trace[874510460] 'compare' (duration: 102.644065ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:58.609353Z","caller":"traceutil/trace.go:171","msg":"trace[1787554279] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"247.037268ms","start":"2026-08-20T01:16:58.362289Z","end":"2026-08-20T01:16:58.609326Z","steps":["trace[1787554279] 'process raft request' (duration: 169.778706ms)","trace[1787554279] 'compare' (duration: 76.882771ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:58.609463Z","caller":"traceutil/trace.go:171","msg":"trace[525568347] linearizableReadLoop","detail":"{readStateIndex:1504; appliedIndex:1503; }","duration":"203.42139ms","start":"2026-08-20T01:16:58.406006Z","end":"2026-08-20T01:16:58.609428Z","steps":["trace[525568347] 'read index received' (duration: 125.947703ms)","trace[525568347] 'applied index is now lower than readState.Index' (duration: 77.470647ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:16:58.609694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.654397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-zln4b\" ","response":"range_response_count:1 size:5487"} {"level":"info","ts":"2026-08-20T01:16:58.609801Z","caller":"traceutil/trace.go:171","msg":"trace[863530857] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-zln4b; range_end:; response_count:1; response_revision:1443; }","duration":"203.801381ms","start":"2026-08-20T01:16:58.405975Z","end":"2026-08-20T01:16:58.609776Z","steps":["trace[863530857] 'agreement among raft nodes before linearized reading' (duration: 203.504563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:58.609926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.04439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:10 size:65278"} {"level":"info","ts":"2026-08-20T01:16:58.609972Z","caller":"traceutil/trace.go:171","msg":"trace[1251712744] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:10; response_revision:1443; }","duration":"197.124152ms","start":"2026-08-20T01:16:58.412835Z","end":"2026-08-20T01:16:58.609959Z","steps":["trace[1251712744] 'agreement among raft nodes before linearized reading' (duration: 196.765601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:58.924806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.401201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-98tj6\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-08-20T01:16:58.924909Z","caller":"traceutil/trace.go:171","msg":"trace[47827029] range","detail":"{range_begin:/registry/pods/kube-system/cilium-98tj6; range_end:; response_count:1; response_revision:1444; }","duration":"147.553035ms","start":"2026-08-20T01:16:58.777331Z","end":"2026-08-20T01:16:58.924884Z","steps":["trace[47827029] 'range keys from in-memory index tree' (duration: 147.203736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:58.924946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.333907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/\" range_end:\"/registry/pods/local-path-storage0\" limit:500 ","response":"range_response_count:1 size:3718"} {"level":"info","ts":"2026-08-20T01:16:58.924996Z","caller":"traceutil/trace.go:171","msg":"trace[312338685] range","detail":"{range_begin:/registry/pods/local-path-storage/; range_end:/registry/pods/local-path-storage0; response_count:1; response_revision:1444; }","duration":"197.43112ms","start":"2026-08-20T01:16:58.727552Z","end":"2026-08-20T01:16:58.924983Z","steps":["trace[312338685] 'range keys from in-memory index tree' (duration: 197.161482ms)"],"step_count":1}