{"level":"warn","ts":"2026-05-07T23:23:54.860733Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:23:54.860844Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.47: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.47:2380","--initial-cluster=instance=https://199.19.213.47:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.47:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.47: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-05-07T23:23:54.860918Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:23:54.860935Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-05-07T23:23:54.860954Z","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-05-07T23:23:54.861732Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"]} {"level":"info","ts":"2026-05-07T23:23:54.861824Z","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.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.47: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-05-07T23:23:54.866082Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.021772ms"} {"level":"info","ts":"2026-05-07T23:23:54.878171Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"944e7e1987ec6eaf","cluster-id":"cb5c24893c5cbfa7"} {"level":"info","ts":"2026-05-07T23:23:54.878296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T23:23:54.878785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 0"} {"level":"info","ts":"2026-05-07T23:23:54.879125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 944e7e1987ec6eaf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T23:23:54.879142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 1"} {"level":"info","ts":"2026-05-07T23:23:54.879367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"warn","ts":"2026-05-07T23:23:54.889498Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T23:23:54.892845Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T23:23:54.89745Z","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-05-07T23:23:54.89968Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"944e7e1987ec6eaf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T23:23:54.900357Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"944e7e1987ec6eaf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T23:23:54.900492Z","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-05-07T23:23:54.900725Z","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-05-07T23:23:54.900821Z","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-05-07T23:23:54.902744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"info","ts":"2026-05-07T23:23:54.902988Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","added-peer-id":"944e7e1987ec6eaf","added-peer-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-05-07T23:23:54.906117Z","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-05-07T23:23:54.906202Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-05-07T23:23:54.906243Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-05-07T23:23:54.906435Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"944e7e1987ec6eaf","initial-advertise-peer-urls":["https://199.19.213.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T23:23:54.906601Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T23:23:55.280459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T23:23:55.280518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T23:23:55.28054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgPreVoteResp from 944e7e1987ec6eaf at term 1"} {"level":"info","ts":"2026-05-07T23:23:55.280552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became candidate at term 2"} {"level":"info","ts":"2026-05-07T23:23:55.280558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgVoteResp from 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-05-07T23:23:55.280577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became leader at term 2"} {"level":"info","ts":"2026-05-07T23:23:55.280584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 944e7e1987ec6eaf elected leader 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-05-07T23:23:55.281267Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"944e7e1987ec6eaf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.47:2379]}","request-path":"/0/members/944e7e1987ec6eaf/attributes","cluster-id":"cb5c24893c5cbfa7","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T23:23:55.281298Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:23:55.281346Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:23:55.281422Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:23:55.283324Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T23:23:55.283661Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T23:23:55.283725Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T23:23:55.285069Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.47:2379"} {"level":"info","ts":"2026-05-07T23:23:55.285343Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:23:55.285531Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:23:55.285602Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-07T23:23:57.924416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.467714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:23:57.924611Z","caller":"traceutil/trace.go:171","msg":"trace[1731669367] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:70; }","duration":"392.71546ms","start":"2026-05-07T23:23:57.531866Z","end":"2026-05-07T23:23:57.924582Z","steps":["trace[1731669367] 'range keys from in-memory index tree' (duration: 392.394402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:57.92469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:57.531849Z","time spent":"392.827392ms","remote":"127.0.0.1:34308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-05-07T23:23:58.445827Z","caller":"traceutil/trace.go:171","msg":"trace[272614365] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"379.926086ms","start":"2026-05-07T23:23:58.065873Z","end":"2026-05-07T23:23:58.445799Z","steps":["trace[272614365] 'read index received' (duration: 379.68017ms)","trace[272614365] 'applied index is now lower than readState.Index' (duration: 245.046µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:23:58.446054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.155061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:23:58.446127Z","caller":"traceutil/trace.go:171","msg":"trace[551470749] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:71; }","duration":"380.290914ms","start":"2026-05-07T23:23:58.065814Z","end":"2026-05-07T23:23:58.446105Z","steps":["trace[551470749] 'agreement among raft nodes before linearized reading' (duration: 380.14437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:58.446188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:58.065789Z","time spent":"380.382846ms","remote":"127.0.0.1:34546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-05-07T23:23:58.446274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.995787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-07T23:23:58.446325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.811918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:23:58.446445Z","caller":"traceutil/trace.go:171","msg":"trace[1145110650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"322.997883ms","start":"2026-05-07T23:23:58.123414Z","end":"2026-05-07T23:23:58.446412Z","steps":["trace[1145110650] 'agreement among raft nodes before linearized reading' (duration: 322.758387ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:58.44637Z","caller":"traceutil/trace.go:171","msg":"trace[1900802904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"275.15756ms","start":"2026-05-07T23:23:58.171193Z","end":"2026-05-07T23:23:58.44635Z","steps":["trace[1900802904] 'agreement among raft nodes before linearized reading' (duration: 274.960356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:58.446519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:58.123388Z","time spent":"323.121546ms","remote":"127.0.0.1:34180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T23:23:58.446338Z","caller":"traceutil/trace.go:171","msg":"trace[340356194] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"422.23746ms","start":"2026-05-07T23:23:58.02363Z","end":"2026-05-07T23:23:58.445868Z","steps":["trace[340356194] 'process raft request' (duration: 421.987265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:58.446601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.199521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:23:58.447093Z","caller":"traceutil/trace.go:171","msg":"trace[1443786901] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:71; }","duration":"380.726993ms","start":"2026-05-07T23:23:58.066346Z","end":"2026-05-07T23:23:58.447073Z","steps":["trace[1443786901] 'agreement among raft nodes before linearized reading' (duration: 379.797131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:58.447137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:58.066319Z","time spent":"380.805455ms","remote":"127.0.0.1:34528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-05-07T23:23:58.447615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:58.023596Z","time spent":"423.346816ms","remote":"127.0.0.1:34250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:23:58.561331Z","caller":"traceutil/trace.go:171","msg":"trace[741928340] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:76; }","duration":"108.92728ms","start":"2026-05-07T23:23:58.452371Z","end":"2026-05-07T23:23:58.561298Z","steps":["trace[741928340] 'read index received' (duration: 14.190947ms)","trace[741928340] 'applied index is now lower than readState.Index' (duration: 94.735133ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:23:58.561573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.163626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:23:58.561625Z","caller":"traceutil/trace.go:171","msg":"trace[485996521] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:73; }","duration":"109.263408ms","start":"2026-05-07T23:23:58.45235Z","end":"2026-05-07T23:23:58.561613Z","steps":["trace[485996521] 'agreement among raft nodes before linearized reading' (duration: 109.104224ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:58.561475Z","caller":"traceutil/trace.go:171","msg":"trace[1484036785] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"109.658717ms","start":"2026-05-07T23:23:58.451721Z","end":"2026-05-07T23:23:58.561379Z","steps":["trace[1484036785] 'process raft request' (duration: 96.242627ms)","trace[1484036785] 'compare' (duration: 13.178644ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:24:25.242262Z","caller":"traceutil/trace.go:171","msg":"trace[904894281] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"158.437484ms","start":"2026-05-07T23:24:25.083752Z","end":"2026-05-07T23:24:25.24219Z","steps":["trace[904894281] 'process raft request' (duration: 158.236649ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:25.24805Z","caller":"traceutil/trace.go:171","msg":"trace[786942972] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"154.7397ms","start":"2026-05-07T23:24:25.093294Z","end":"2026-05-07T23:24:25.248034Z","steps":["trace[786942972] 'process raft request' (duration: 154.516384ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:25.248116Z","caller":"traceutil/trace.go:171","msg":"trace[562788321] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"148.135229ms","start":"2026-05-07T23:24:25.099959Z","end":"2026-05-07T23:24:25.248095Z","steps":["trace[562788321] 'process raft request' (duration: 147.973334ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:25.24814Z","caller":"traceutil/trace.go:171","msg":"trace[28888936] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"146.890611ms","start":"2026-05-07T23:24:25.101242Z","end":"2026-05-07T23:24:25.248133Z","steps":["trace[28888936] 'process raft request' (duration: 146.747527ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:25.248177Z","caller":"traceutil/trace.go:171","msg":"trace[1603161533] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"142.505831ms","start":"2026-05-07T23:24:25.105666Z","end":"2026-05-07T23:24:25.248172Z","steps":["trace[1603161533] 'process raft request' (duration: 142.378998ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:25.248223Z","caller":"traceutil/trace.go:171","msg":"trace[509890492] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"143.093784ms","start":"2026-05-07T23:24:25.105058Z","end":"2026-05-07T23:24:25.248151Z","steps":["trace[509890492] 'process raft request' (duration: 142.959661ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:25.248279Z","caller":"traceutil/trace.go:171","msg":"trace[923583610] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"130.281281ms","start":"2026-05-07T23:24:25.117993Z","end":"2026-05-07T23:24:25.248274Z","steps":["trace[923583610] 'process raft request' (duration: 130.077686ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:25.248403Z","caller":"traceutil/trace.go:171","msg":"trace[980754370] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"119.31269ms","start":"2026-05-07T23:24:25.129074Z","end":"2026-05-07T23:24:25.248386Z","steps":["trace[980754370] 'process raft request' (duration: 119.121836ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:25.248163Z","caller":"traceutil/trace.go:171","msg":"trace[1864666002] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"146.882381ms","start":"2026-05-07T23:24:25.101183Z","end":"2026-05-07T23:24:25.248065Z","steps":["trace[1864666002] 'process raft request' (duration: 146.775768ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:25.2485Z","caller":"traceutil/trace.go:171","msg":"trace[546458247] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"107.259545ms","start":"2026-05-07T23:24:25.141232Z","end":"2026-05-07T23:24:25.248491Z","steps":["trace[546458247] 'process raft request' (duration: 107.077191ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:25.249046Z","caller":"traceutil/trace.go:171","msg":"trace[198623245] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"155.662522ms","start":"2026-05-07T23:24:25.093357Z","end":"2026-05-07T23:24:25.24902Z","steps":["trace[198623245] 'process raft request' (duration: 154.540765ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:25.468966Z","caller":"traceutil/trace.go:171","msg":"trace[843263916] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"123.738961ms","start":"2026-05-07T23:24:25.345172Z","end":"2026-05-07T23:24:25.468911Z","steps":["trace[843263916] 'process raft request' (duration: 71.328143ms)","trace[843263916] 'compare' (duration: 52.163282ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:24:25.469043Z","caller":"traceutil/trace.go:171","msg":"trace[103500979] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"101.481541ms","start":"2026-05-07T23:24:25.367537Z","end":"2026-05-07T23:24:25.469019Z","steps":["trace[103500979] 'process raft request' (duration: 101.323708ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:25.469234Z","caller":"traceutil/trace.go:171","msg":"trace[2037156829] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"116.105976ms","start":"2026-05-07T23:24:25.352867Z","end":"2026-05-07T23:24:25.468973Z","steps":["trace[2037156829] 'process raft request' (duration: 115.949653ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:30.830485Z","caller":"traceutil/trace.go:171","msg":"trace[1770527087] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"185.492384ms","start":"2026-05-07T23:24:30.644966Z","end":"2026-05-07T23:24:30.830459Z","steps":["trace[1770527087] 'process raft request' (duration: 184.996642ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:30.830844Z","caller":"traceutil/trace.go:171","msg":"trace[1786002165] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"152.270554ms","start":"2026-05-07T23:24:30.678107Z","end":"2026-05-07T23:24:30.830378Z","steps":["trace[1786002165] 'read index received' (duration: 151.635049ms)","trace[1786002165] 'applied index is now lower than readState.Index' (duration: 634.915µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:24:30.830855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.723664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:24:30.831022Z","caller":"traceutil/trace.go:171","msg":"trace[1561784709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"152.948299ms","start":"2026-05-07T23:24:30.678054Z","end":"2026-05-07T23:24:30.831002Z","steps":["trace[1561784709] 'agreement among raft nodes before linearized reading' (duration: 152.459508ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:31.553885Z","caller":"traceutil/trace.go:171","msg":"trace[1613499296] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"227.327061ms","start":"2026-05-07T23:24:31.326514Z","end":"2026-05-07T23:24:31.553841Z","steps":["trace[1613499296] 'process raft request' (duration: 224.61605ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:31.766989Z","caller":"traceutil/trace.go:171","msg":"trace[747449216] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"157.136796ms","start":"2026-05-07T23:24:31.609827Z","end":"2026-05-07T23:24:31.766963Z","steps":["trace[747449216] 'process raft request' (duration: 157.008983ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:31.771466Z","caller":"traceutil/trace.go:171","msg":"trace[1283275004] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"101.195985ms","start":"2026-05-07T23:24:31.670256Z","end":"2026-05-07T23:24:31.771452Z","steps":["trace[1283275004] 'process raft request' (duration: 100.836687ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:32.546168Z","caller":"traceutil/trace.go:171","msg":"trace[320509194] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"679.584788ms","start":"2026-05-07T23:24:31.866551Z","end":"2026-05-07T23:24:32.546136Z","steps":["trace[320509194] 'process raft request' (duration: 657.258697ms)","trace[320509194] 'compare' (duration: 21.627794ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:24:32.546595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:31.866514Z","time spent":"679.731172ms","remote":"127.0.0.1:34344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:24:32.547924Z","caller":"traceutil/trace.go:171","msg":"trace[1410043229] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"304.219979ms","start":"2026-05-07T23:24:32.243687Z","end":"2026-05-07T23:24:32.547907Z","steps":["trace[1410043229] 'read index received' (duration: 280.21796ms)","trace[1410043229] 'applied index is now lower than readState.Index' (duration: 24.001379ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:24:32.548091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.404554ms","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-05-07T23:24:32.54812Z","caller":"traceutil/trace.go:171","msg":"trace[888351619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"304.498266ms","start":"2026-05-07T23:24:32.243613Z","end":"2026-05-07T23:24:32.548111Z","steps":["trace[888351619] 'agreement among raft nodes before linearized reading' (duration: 304.389463ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:32.548067Z","caller":"traceutil/trace.go:171","msg":"trace[1421207260] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"377.854164ms","start":"2026-05-07T23:24:32.170178Z","end":"2026-05-07T23:24:32.548032Z","steps":["trace[1421207260] 'process raft request' (duration: 375.92113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:32.54815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:32.243583Z","time spent":"304.561987ms","remote":"127.0.0.1:34430","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-05-07T23:24:32.54822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.201925ms","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-05-07T23:24:32.548658Z","caller":"traceutil/trace.go:171","msg":"trace[364523329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"261.664536ms","start":"2026-05-07T23:24:32.286963Z","end":"2026-05-07T23:24:32.548627Z","steps":["trace[364523329] 'agreement among raft nodes before linearized reading' (duration: 261.203925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:32.548622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:32.170156Z","time spent":"378.09367ms","remote":"127.0.0.1:34260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:24:47.528456Z","caller":"traceutil/trace.go:171","msg":"trace[1066635699] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"128.546761ms","start":"2026-05-07T23:24:47.399891Z","end":"2026-05-07T23:24:47.528437Z","steps":["trace[1066635699] 'process raft request' (duration: 128.373147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:52.924377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.902802ms","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:5 size:1050260"} {"level":"info","ts":"2026-05-07T23:24:52.924491Z","caller":"traceutil/trace.go:171","msg":"trace[1198559508] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:835; }","duration":"109.112976ms","start":"2026-05-07T23:24:52.81535Z","end":"2026-05-07T23:24:52.924463Z","steps":["trace[1198559508] 'range keys from in-memory index tree' (duration: 107.824528ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:23.005216Z","caller":"traceutil/trace.go:171","msg":"trace[1095410910] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"137.891265ms","start":"2026-05-07T23:25:22.867284Z","end":"2026-05-07T23:25:23.005176Z","steps":["trace[1095410910] 'process raft request' (duration: 137.726951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:23.337885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.679391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:25:23.338038Z","caller":"traceutil/trace.go:171","msg":"trace[1095221877] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1026; }","duration":"391.774514ms","start":"2026-05-07T23:25:22.946241Z","end":"2026-05-07T23:25:23.338016Z","steps":["trace[1095221877] 'read index received' (duration: 58.810226ms)","trace[1095221877] 'applied index is now lower than readState.Index' (duration: 332.962428ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:25:23.338134Z","caller":"traceutil/trace.go:171","msg":"trace[737180158] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"467.864344ms","start":"2026-05-07T23:25:22.870256Z","end":"2026-05-07T23:25:23.33812Z","steps":["trace[737180158] 'process raft request' (duration: 332.648361ms)","trace[737180158] 'compare' (duration: 134.572799ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:25:23.338255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:22.870236Z","time spent":"467.957206ms","remote":"127.0.0.1:34430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:25:23.338464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.214734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:25:23.338522Z","caller":"traceutil/trace.go:171","msg":"trace[1561969693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"392.304265ms","start":"2026-05-07T23:25:22.946203Z","end":"2026-05-07T23:25:23.338507Z","steps":["trace[1561969693] 'agreement among raft nodes before linearized reading' (duration: 392.213503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:23.338561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:22.946185Z","time spent":"392.368247ms","remote":"127.0.0.1:34180","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-05-07T23:25:23.354817Z","caller":"traceutil/trace.go:171","msg":"trace[1244105538] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"320.428542ms","start":"2026-05-07T23:25:23.034368Z","end":"2026-05-07T23:25:23.354797Z","steps":["trace[1244105538] 'process raft request' (duration: 320.210067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:23.354945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:23.034341Z","time spent":"320.540214ms","remote":"127.0.0.1:34430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:25:23.355021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.430979ms","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-05-07T23:25:23.355104Z","caller":"traceutil/trace.go:171","msg":"trace[1465903751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"215.567141ms","start":"2026-05-07T23:25:23.139519Z","end":"2026-05-07T23:25:23.355086Z","steps":["trace[1465903751] 'agreement among raft nodes before linearized reading' (duration: 215.314646ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:24.030023Z","caller":"traceutil/trace.go:171","msg":"trace[120335985] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"244.75565ms","start":"2026-05-07T23:25:23.785234Z","end":"2026-05-07T23:25:24.029989Z","steps":["trace[120335985] 'process raft request' (duration: 244.490384ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:24.249492Z","caller":"traceutil/trace.go:171","msg":"trace[182701826] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1031; }","duration":"152.161501ms","start":"2026-05-07T23:25:24.097312Z","end":"2026-05-07T23:25:24.249474Z","steps":["trace[182701826] 'read index received' (duration: 104.135342ms)","trace[182701826] 'applied index is now lower than readState.Index' (duration: 48.025429ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:25:24.249522Z","caller":"traceutil/trace.go:171","msg":"trace[1447161816] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"216.292718ms","start":"2026-05-07T23:25:24.033205Z","end":"2026-05-07T23:25:24.249498Z","steps":["trace[1447161816] 'process raft request' (duration: 168.311141ms)","trace[1447161816] 'compare' (duration: 47.864885ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:25:24.249643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.322124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T23:25:24.249664Z","caller":"traceutil/trace.go:171","msg":"trace[1197231242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"152.384986ms","start":"2026-05-07T23:25:24.097273Z","end":"2026-05-07T23:25:24.249658Z","steps":["trace[1197231242] 'agreement among raft nodes before linearized reading' (duration: 152.278304ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:38.532039Z","caller":"traceutil/trace.go:171","msg":"trace[1077963458] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"190.540499ms","start":"2026-05-07T23:25:38.341477Z","end":"2026-05-07T23:25:38.532018Z","steps":["trace[1077963458] 'read index received' (duration: 190.369824ms)","trace[1077963458] 'applied index is now lower than readState.Index' (duration: 169.785µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:25:38.532156Z","caller":"traceutil/trace.go:171","msg":"trace[582243511] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"202.271548ms","start":"2026-05-07T23:25:38.329833Z","end":"2026-05-07T23:25:38.532105Z","steps":["trace[582243511] 'process raft request' (duration: 202.079424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:38.532243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.756544ms","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-05-07T23:25:38.532275Z","caller":"traceutil/trace.go:171","msg":"trace[1488567386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1047; }","duration":"190.841156ms","start":"2026-05-07T23:25:38.341427Z","end":"2026-05-07T23:25:38.532268Z","steps":["trace[1488567386] 'agreement among raft nodes before linearized reading' (duration: 190.672322ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:43.860218Z","caller":"traceutil/trace.go:171","msg":"trace[793076094] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"107.585432ms","start":"2026-05-07T23:25:43.752607Z","end":"2026-05-07T23:25:43.860192Z","steps":["trace[793076094] 'process raft request' (duration: 107.439538ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:44.806257Z","caller":"traceutil/trace.go:171","msg":"trace[1645568123] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"166.797556ms","start":"2026-05-07T23:25:44.639423Z","end":"2026-05-07T23:25:44.80622Z","steps":["trace[1645568123] 'process raft request' (duration: 166.631942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:45.094015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.862813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:25:45.094152Z","caller":"traceutil/trace.go:171","msg":"trace[1728356582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1069; }","duration":"148.068727ms","start":"2026-05-07T23:25:44.946054Z","end":"2026-05-07T23:25:45.094122Z","steps":["trace[1728356582] 'range keys from in-memory index tree' (duration: 147.70407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:45.858468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.410497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:25:45.858534Z","caller":"traceutil/trace.go:171","msg":"trace[1397877132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"329.53176ms","start":"2026-05-07T23:25:45.528989Z","end":"2026-05-07T23:25:45.858521Z","steps":["trace[1397877132] 'range keys from in-memory index tree' (duration: 329.237533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:45.858561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:45.528967Z","time spent":"329.58895ms","remote":"127.0.0.1:34430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T23:25:46.097305Z","caller":"traceutil/trace.go:171","msg":"trace[917590166] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"234.451544ms","start":"2026-05-07T23:25:45.862845Z","end":"2026-05-07T23:25:46.097297Z","steps":["trace[917590166] 'process raft request' (duration: 234.177718ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:46.09716Z","caller":"traceutil/trace.go:171","msg":"trace[1872134028] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"150.916573ms","start":"2026-05-07T23:25:45.946225Z","end":"2026-05-07T23:25:46.097142Z","steps":["trace[1872134028] 'read index received' (duration: 150.826191ms)","trace[1872134028] 'applied index is now lower than readState.Index' (duration: 89.782µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:25:46.097687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.453485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:25:46.097711Z","caller":"traceutil/trace.go:171","msg":"trace[360951711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"151.530987ms","start":"2026-05-07T23:25:45.946173Z","end":"2026-05-07T23:25:46.097704Z","steps":["trace[360951711] 'agreement among raft nodes before linearized reading' (duration: 151.364793ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:46.823896Z","caller":"traceutil/trace.go:171","msg":"trace[220901500] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"167.91827ms","start":"2026-05-07T23:25:46.655951Z","end":"2026-05-07T23:25:46.82387Z","steps":["trace[220901500] 'process raft request' (duration: 167.624024ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:47.370183Z","caller":"traceutil/trace.go:171","msg":"trace[167241965] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"230.023803ms","start":"2026-05-07T23:25:47.140137Z","end":"2026-05-07T23:25:47.37016Z","steps":["trace[167241965] 'read index received' (duration: 229.760067ms)","trace[167241965] 'applied index is now lower than readState.Index' (duration: 262.516µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:25:47.37038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.232028ms","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-05-07T23:25:47.370422Z","caller":"traceutil/trace.go:171","msg":"trace[1376153019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1075; }","duration":"230.32892ms","start":"2026-05-07T23:25:47.140084Z","end":"2026-05-07T23:25:47.370413Z","steps":["trace[1376153019] 'agreement among raft nodes before linearized reading' (duration: 230.168537ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:47.370566Z","caller":"traceutil/trace.go:171","msg":"trace[222645245] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"541.98206ms","start":"2026-05-07T23:25:46.828507Z","end":"2026-05-07T23:25:47.370489Z","steps":["trace[222645245] 'process raft request' (duration: 541.476158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:47.370765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:46.828482Z","time spent":"542.171805ms","remote":"127.0.0.1:34430","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-05-07T23:25:52.305082Z","caller":"traceutil/trace.go:171","msg":"trace[989616101] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"145.175832ms","start":"2026-05-07T23:25:52.159888Z","end":"2026-05-07T23:25:52.305064Z","steps":["trace[989616101] 'read index received' (duration: 145.006558ms)","trace[989616101] 'applied index is now lower than readState.Index' (duration: 168.534µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:25:52.305199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.294094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:25:52.30522Z","caller":"traceutil/trace.go:171","msg":"trace[905264393] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1093; }","duration":"145.362616ms","start":"2026-05-07T23:25:52.159852Z","end":"2026-05-07T23:25:52.305215Z","steps":["trace[905264393] 'agreement among raft nodes before linearized reading' (duration: 145.311115ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:52.305185Z","caller":"traceutil/trace.go:171","msg":"trace[901060037] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"150.169146ms","start":"2026-05-07T23:25:52.154987Z","end":"2026-05-07T23:25:52.305156Z","steps":["trace[901060037] 'process raft request' (duration: 149.963261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:52.305554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.048686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:25:52.305746Z","caller":"traceutil/trace.go:171","msg":"trace[2058941436] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1093; }","duration":"113.364463ms","start":"2026-05-07T23:25:52.19236Z","end":"2026-05-07T23:25:52.305725Z","steps":["trace[2058941436] 'agreement among raft nodes before linearized reading' (duration: 113.017045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:01.957595Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767208355858693,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:26:02.317199Z","caller":"traceutil/trace.go:171","msg":"trace[1020791022] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1212; }","duration":"860.343832ms","start":"2026-05-07T23:26:01.45683Z","end":"2026-05-07T23:26:02.317174Z","steps":["trace[1020791022] 'read index received' (duration: 860.191219ms)","trace[1020791022] 'applied index is now lower than readState.Index' (duration: 151.753µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:02.317564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.126985ms","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-05-07T23:26:02.317601Z","caller":"traceutil/trace.go:171","msg":"trace[362405285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"647.219387ms","start":"2026-05-07T23:26:01.670372Z","end":"2026-05-07T23:26:02.317592Z","steps":["trace[362405285] 'agreement among raft nodes before linearized reading' (duration: 647.095254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:02.317631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:01.67035Z","time spent":"647.273119ms","remote":"127.0.0.1:34430","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":"info","ts":"2026-05-07T23:26:02.31769Z","caller":"traceutil/trace.go:171","msg":"trace[529221021] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"885.666942ms","start":"2026-05-07T23:26:01.431972Z","end":"2026-05-07T23:26:02.317638Z","steps":["trace[529221021] 'process raft request' (duration: 884.957826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:02.317819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.629845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:26:02.317856Z","caller":"traceutil/trace.go:171","msg":"trace[1992261809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1173; }","duration":"372.687757ms","start":"2026-05-07T23:26:01.945159Z","end":"2026-05-07T23:26:02.317847Z","steps":["trace[1992261809] 'agreement among raft nodes before linearized reading' (duration: 372.610905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:02.317885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:01.945149Z","time spent":"372.730307ms","remote":"127.0.0.1:34180","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-05-07T23:26:02.317873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:01.43195Z","time spent":"885.833076ms","remote":"127.0.0.1:34430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:02.317913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.304124ms","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-05-07T23:26:02.318028Z","caller":"traceutil/trace.go:171","msg":"trace[129166723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1173; }","duration":"142.457428ms","start":"2026-05-07T23:26:02.175542Z","end":"2026-05-07T23:26:02.317999Z","steps":["trace[129166723] 'agreement among raft nodes before linearized reading' (duration: 142.152601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:02.317646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.741822ms","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-05-07T23:26:02.31854Z","caller":"traceutil/trace.go:171","msg":"trace[82797679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1173; }","duration":"861.726805ms","start":"2026-05-07T23:26:01.456792Z","end":"2026-05-07T23:26:02.318519Z","steps":["trace[82797679] 'agreement among raft nodes before linearized reading' (duration: 860.625429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:02.318617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:01.456776Z","time spent":"861.824708ms","remote":"127.0.0.1:34430","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-05-07T23:26:18.751317Z","caller":"traceutil/trace.go:171","msg":"trace[898532879] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"377.538649ms","start":"2026-05-07T23:26:18.373749Z","end":"2026-05-07T23:26:18.751287Z","steps":["trace[898532879] 'process raft request' (duration: 377.306443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:18.751481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:18.373725Z","time spent":"377.674293ms","remote":"127.0.0.1:34344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:26:18.867102Z","caller":"traceutil/trace.go:171","msg":"trace[1968923742] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1285; }","duration":"443.054886ms","start":"2026-05-07T23:26:18.424023Z","end":"2026-05-07T23:26:18.867078Z","steps":["trace[1968923742] 'read index received' (duration: 327.067395ms)","trace[1968923742] 'applied index is now lower than readState.Index' (duration: 115.986451ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:18.867194Z","caller":"traceutil/trace.go:171","msg":"trace[1180413284] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"465.896758ms","start":"2026-05-07T23:26:18.401253Z","end":"2026-05-07T23:26:18.86715Z","steps":["trace[1180413284] 'process raft request' (duration: 412.060548ms)","trace[1180413284] 'compare' (duration: 53.654596ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:18.867328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.23196ms","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-05-07T23:26:18.86733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.288981ms","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-05-07T23:26:18.86737Z","caller":"traceutil/trace.go:171","msg":"trace[221604866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1244; }","duration":"443.300192ms","start":"2026-05-07T23:26:18.42406Z","end":"2026-05-07T23:26:18.86736Z","steps":["trace[221604866] 'agreement among raft nodes before linearized reading' (duration: 443.175879ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:18.867382Z","caller":"traceutil/trace.go:171","msg":"trace[245547679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1244; }","duration":"443.385644ms","start":"2026-05-07T23:26:18.423983Z","end":"2026-05-07T23:26:18.867369Z","steps":["trace[245547679] 'agreement among raft nodes before linearized reading' (duration: 443.22244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:18.867413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:18.424054Z","time spent":"443.351393ms","remote":"127.0.0.1:34430","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-05-07T23:26:18.867423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:18.423965Z","time spent":"443.449735ms","remote":"127.0.0.1:34430","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-05-07T23:26:18.867538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.456105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T23:26:18.867544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.975859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-05-07T23:26:18.867391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.674237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:26:18.867651Z","caller":"traceutil/trace.go:171","msg":"trace[1655782701] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1244; }","duration":"376.008484ms","start":"2026-05-07T23:26:18.491637Z","end":"2026-05-07T23:26:18.867645Z","steps":["trace[1655782701] 'agreement among raft nodes before linearized reading' (duration: 375.658466ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:18.867653Z","caller":"traceutil/trace.go:171","msg":"trace[1095613055] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1244; }","duration":"112.217555ms","start":"2026-05-07T23:26:18.755415Z","end":"2026-05-07T23:26:18.867632Z","steps":["trace[1095613055] 'agreement among raft nodes before linearized reading' (duration: 112.01089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:18.86757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.141133ms","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-05-07T23:26:18.867713Z","caller":"traceutil/trace.go:171","msg":"trace[1400845260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1244; }","duration":"438.345539ms","start":"2026-05-07T23:26:18.429362Z","end":"2026-05-07T23:26:18.867707Z","steps":["trace[1400845260] 'agreement among raft nodes before linearized reading' (duration: 437.996661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:18.86768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:18.401228Z","time spent":"466.047762ms","remote":"127.0.0.1:34430","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-05-07T23:26:18.867731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:18.429341Z","time spent":"438.3859ms","remote":"127.0.0.1:34430","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":"info","ts":"2026-05-07T23:26:18.867588Z","caller":"traceutil/trace.go:171","msg":"trace[1101815125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1244; }","duration":"439.509195ms","start":"2026-05-07T23:26:18.428063Z","end":"2026-05-07T23:26:18.867572Z","steps":["trace[1101815125] 'agreement among raft nodes before linearized reading' (duration: 439.369562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:18.867676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:18.491619Z","time spent":"376.050765ms","remote":"127.0.0.1:47012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:26:18.86782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:18.428057Z","time spent":"439.753651ms","remote":"127.0.0.1:34430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T23:26:19.468601Z","caller":"traceutil/trace.go:171","msg":"trace[1046217121] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"246.650838ms","start":"2026-05-07T23:26:19.221915Z","end":"2026-05-07T23:26:19.468566Z","steps":["trace[1046217121] 'process raft request' (duration: 246.487074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:19.876024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.284259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:26:19.876216Z","caller":"traceutil/trace.go:171","msg":"trace[372809942] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"322.864199ms","start":"2026-05-07T23:26:19.553332Z","end":"2026-05-07T23:26:19.876196Z","steps":["trace[372809942] 'process raft request' (duration: 111.251663ms)","trace[372809942] 'compare' (duration: 211.128135ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:19.876322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:19.553315Z","time spent":"322.971292ms","remote":"127.0.0.1:34202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:21.469934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.890895ms","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-05-07T23:26:21.469995Z","caller":"traceutil/trace.go:171","msg":"trace[2136157993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1264; }","duration":"373.995128ms","start":"2026-05-07T23:26:21.095985Z","end":"2026-05-07T23:26:21.46998Z","steps":["trace[2136157993] 'range keys from in-memory index tree' (duration: 373.755532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:21.470021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:21.095966Z","time spent":"374.049579ms","remote":"127.0.0.1:34430","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":"info","ts":"2026-05-07T23:26:21.753352Z","caller":"traceutil/trace.go:171","msg":"trace[1185891662] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"279.655822ms","start":"2026-05-07T23:26:21.473678Z","end":"2026-05-07T23:26:21.753334Z","steps":["trace[1185891662] 'process raft request' (duration: 279.54363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:32.736587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.939988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:26:32.736668Z","caller":"traceutil/trace.go:171","msg":"trace[1911416443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1338; }","duration":"791.061811ms","start":"2026-05-07T23:26:31.945587Z","end":"2026-05-07T23:26:32.736649Z","steps":["trace[1911416443] 'range keys from in-memory index tree' (duration: 790.774784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:32.736704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:31.945568Z","time spent":"791.128023ms","remote":"127.0.0.1:34180","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-05-07T23:26:32.737117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.515896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-07T23:26:32.737149Z","caller":"traceutil/trace.go:171","msg":"trace[377723331] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1338; }","duration":"387.586918ms","start":"2026-05-07T23:26:32.349553Z","end":"2026-05-07T23:26:32.73714Z","steps":["trace[377723331] 'range keys from in-memory index tree' (duration: 387.349853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:32.737188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:32.349531Z","time spent":"387.65023ms","remote":"127.0.0.1:34430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-07T23:26:33.107222Z","caller":"traceutil/trace.go:171","msg":"trace[1415325564] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"365.31284ms","start":"2026-05-07T23:26:32.74188Z","end":"2026-05-07T23:26:33.107193Z","steps":["trace[1415325564] 'process raft request' (duration: 365.123955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:33.107379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:32.741859Z","time spent":"365.440552ms","remote":"127.0.0.1:34430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:26:33.10872Z","caller":"traceutil/trace.go:171","msg":"trace[1705359780] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"116.257058ms","start":"2026-05-07T23:26:32.992444Z","end":"2026-05-07T23:26:33.108701Z","steps":["trace[1705359780] 'process raft request' (duration: 115.684964ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:33.225706Z","caller":"traceutil/trace.go:171","msg":"trace[902501584] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"112.293467ms","start":"2026-05-07T23:26:33.113371Z","end":"2026-05-07T23:26:33.225665Z","steps":["trace[902501584] 'process raft request' (duration: 111.869117ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:33.225723Z","caller":"traceutil/trace.go:171","msg":"trace[1767022620] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"112.086842ms","start":"2026-05-07T23:26:33.113605Z","end":"2026-05-07T23:26:33.225692Z","steps":["trace[1767022620] 'process raft request' (duration: 111.965749ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:33.225831Z","caller":"traceutil/trace.go:171","msg":"trace[550457098] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"111.312023ms","start":"2026-05-07T23:26:33.114496Z","end":"2026-05-07T23:26:33.225808Z","steps":["trace[550457098] 'process raft request' (duration: 111.129339ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:59.508682Z","caller":"traceutil/trace.go:171","msg":"trace[1752475935] linearizableReadLoop","detail":"{readStateIndex:1511; appliedIndex:1510; }","duration":"187.762282ms","start":"2026-05-07T23:26:59.320894Z","end":"2026-05-07T23:26:59.508657Z","steps":["trace[1752475935] 'read index received' (duration: 187.620488ms)","trace[1752475935] 'applied index is now lower than readState.Index' (duration: 140.724µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:59.508793Z","caller":"traceutil/trace.go:171","msg":"trace[1076691278] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"188.892448ms","start":"2026-05-07T23:26:59.319849Z","end":"2026-05-07T23:26:59.508741Z","steps":["trace[1076691278] 'process raft request' (duration: 188.667963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.508898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.990046ms","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-05-07T23:26:59.508953Z","caller":"traceutil/trace.go:171","msg":"trace[862072888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1460; }","duration":"188.079519ms","start":"2026-05-07T23:26:59.320862Z","end":"2026-05-07T23:26:59.508941Z","steps":["trace[862072888] 'agreement among raft nodes before linearized reading' (duration: 187.882125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.50905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.734814ms","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-05-07T23:26:59.509096Z","caller":"traceutil/trace.go:171","msg":"trace[665210430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1460; }","duration":"153.819286ms","start":"2026-05-07T23:26:59.355266Z","end":"2026-05-07T23:26:59.509085Z","steps":["trace[665210430] 'agreement among raft nodes before linearized reading' (duration: 153.648992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.509112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.729997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:26:59.509201Z","caller":"traceutil/trace.go:171","msg":"trace[126795995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1460; }","duration":"168.86378ms","start":"2026-05-07T23:26:59.340315Z","end":"2026-05-07T23:26:59.509179Z","steps":["trace[126795995] 'agreement among raft nodes before linearized reading' (duration: 168.623095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.509761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.806932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.47\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T23:26:59.509811Z","caller":"traceutil/trace.go:171","msg":"trace[913745443] range","detail":"{range_begin:/registry/masterleases/199.19.213.47; range_end:; response_count:1; response_revision:1460; }","duration":"127.888003ms","start":"2026-05-07T23:26:59.38191Z","end":"2026-05-07T23:26:59.509798Z","steps":["trace[913745443] 'agreement among raft nodes before linearized reading' (duration: 127.75768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.510262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.112391ms","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-05-07T23:26:59.5103Z","caller":"traceutil/trace.go:171","msg":"trace[346643888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1460; }","duration":"171.174132ms","start":"2026-05-07T23:26:59.339114Z","end":"2026-05-07T23:26:59.510288Z","steps":["trace[346643888] 'agreement among raft nodes before linearized reading' (duration: 169.774051ms)"],"step_count":1}