{"level":"warn","ts":"2026-08-22T18:44:37.053536Z","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-22T18:44:37.053798Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.11: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.11:2380","--initial-cluster=instance=https://199.19.213.11:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.11:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.11: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-22T18:44:37.05388Z","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-22T18:44:37.053891Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-08-22T18:44:37.053911Z","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-22T18:44:37.05471Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"]} {"level":"info","ts":"2026-08-22T18:44:37.054796Z","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.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.11: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-22T18:44:37.062372Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.106982ms"} {"level":"info","ts":"2026-08-22T18:44:37.075282Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ce8ad90e35537ee3","cluster-id":"3c17b62c6529d987"} {"level":"info","ts":"2026-08-22T18:44:37.075387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T18:44:37.075413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 0"} {"level":"info","ts":"2026-08-22T18:44:37.07543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ce8ad90e35537ee3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T18:44:37.075474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 1"} {"level":"info","ts":"2026-08-22T18:44:37.075522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"warn","ts":"2026-08-22T18:44:37.085325Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T18:44:37.086917Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T18:44:37.088144Z","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-22T18:44:37.089815Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ce8ad90e35537ee3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T18:44:37.090229Z","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-22T18:44:37.090397Z","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-22T18:44:37.090424Z","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-22T18:44:37.09027Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ce8ad90e35537ee3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T18:44:37.092499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"info","ts":"2026-08-22T18:44:37.092648Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","added-peer-id":"ce8ad90e35537ee3","added-peer-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-08-22T18:44:37.097282Z","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-22T18:44:37.097593Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-08-22T18:44:37.097664Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-08-22T18:44:37.098041Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ce8ad90e35537ee3","initial-advertise-peer-urls":["https://199.19.213.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T18:44:37.098088Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T18:44:38.076187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T18:44:38.076423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T18:44:38.076597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgPreVoteResp from ce8ad90e35537ee3 at term 1"} {"level":"info","ts":"2026-08-22T18:44:38.076699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became candidate at term 2"} {"level":"info","ts":"2026-08-22T18:44:38.076839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgVoteResp from ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-08-22T18:44:38.076986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became leader at term 2"} {"level":"info","ts":"2026-08-22T18:44:38.077085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ce8ad90e35537ee3 elected leader ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-08-22T18:44:38.088697Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T18:44:38.096915Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ce8ad90e35537ee3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.11:2379]}","request-path":"/0/members/ce8ad90e35537ee3/attributes","cluster-id":"3c17b62c6529d987","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T18:44:38.096972Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T18:44:38.097227Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T18:44:38.097479Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T18:44:38.097587Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T18:44:38.100175Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T18:44:38.101418Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.11:2379"} {"level":"info","ts":"2026-08-22T18:44:38.122365Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T18:44:38.122797Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T18:44:38.12294Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T18:44:47.527342Z","caller":"traceutil/trace.go:171","msg":"trace[1863499364] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"130.211238ms","start":"2026-08-22T18:44:47.397085Z","end":"2026-08-22T18:44:47.527296Z","steps":["trace[1863499364] 'process raft request' (duration: 129.911371ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:47.704086Z","caller":"traceutil/trace.go:171","msg":"trace[775180954] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"119.614572ms","start":"2026-08-22T18:44:47.584448Z","end":"2026-08-22T18:44:47.704063Z","steps":["trace[775180954] 'read index received' (duration: 119.51302ms)","trace[775180954] 'applied index is now lower than readState.Index' (duration: 100.342µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:44:47.704174Z","caller":"traceutil/trace.go:171","msg":"trace[469686658] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"162.177551ms","start":"2026-08-22T18:44:47.54193Z","end":"2026-08-22T18:44:47.704107Z","steps":["trace[469686658] 'process raft request' (duration: 161.970797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:47.704285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.812808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-22T18:44:47.70441Z","caller":"traceutil/trace.go:171","msg":"trace[414334505] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:303; }","duration":"119.981471ms","start":"2026-08-22T18:44:47.584406Z","end":"2026-08-22T18:44:47.704387Z","steps":["trace[414334505] 'agreement among raft nodes before linearized reading' (duration: 119.803597ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:47.98402Z","caller":"traceutil/trace.go:171","msg":"trace[671179732] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:312; }","duration":"249.482803ms","start":"2026-08-22T18:44:47.734513Z","end":"2026-08-22T18:44:47.983996Z","steps":["trace[671179732] 'read index received' (duration: 60.099264ms)","trace[671179732] 'applied index is now lower than readState.Index' (duration: 189.382179ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:44:47.984069Z","caller":"traceutil/trace.go:171","msg":"trace[687334296] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"267.848256ms","start":"2026-08-22T18:44:47.716178Z","end":"2026-08-22T18:44:47.984026Z","steps":["trace[687334296] 'process raft request' (duration: 267.710903ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:47.984065Z","caller":"traceutil/trace.go:171","msg":"trace[1276680170] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"268.732399ms","start":"2026-08-22T18:44:47.715301Z","end":"2026-08-22T18:44:47.984034Z","steps":["trace[1276680170] 'process raft request' (duration: 239.741907ms)","trace[1276680170] 'compare' (duration: 28.693103ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:44:47.984132Z","caller":"traceutil/trace.go:171","msg":"trace[258458473] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"266.641289ms","start":"2026-08-22T18:44:47.717482Z","end":"2026-08-22T18:44:47.984123Z","steps":["trace[258458473] 'process raft request' (duration: 266.474824ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:47.984465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.922135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-08-22T18:44:47.984613Z","caller":"traceutil/trace.go:171","msg":"trace[2045669967] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:307; }","duration":"250.093888ms","start":"2026-08-22T18:44:47.734464Z","end":"2026-08-22T18:44:47.984558Z","steps":["trace[2045669967] 'agreement among raft nodes before linearized reading' (duration: 249.824662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.014128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.98552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-22T18:44:48.014213Z","caller":"traceutil/trace.go:171","msg":"trace[1091739893] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:308; }","duration":"180.106174ms","start":"2026-08-22T18:44:47.834089Z","end":"2026-08-22T18:44:48.014195Z","steps":["trace[1091739893] 'agreement among raft nodes before linearized reading' (duration: 179.914369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.014209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.933322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-22T18:44:48.014153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.79619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-08-22T18:44:48.014397Z","caller":"traceutil/trace.go:171","msg":"trace[23492179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:308; }","duration":"250.180998ms","start":"2026-08-22T18:44:47.764178Z","end":"2026-08-22T18:44:48.014359Z","steps":["trace[23492179] 'agreement among raft nodes before linearized reading' (duration: 249.937262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.014398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.697483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-22T18:44:48.014418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.93035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-08-22T18:44:48.01454Z","caller":"traceutil/trace.go:171","msg":"trace[1934680123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:308; }","duration":"205.931747ms","start":"2026-08-22T18:44:47.80859Z","end":"2026-08-22T18:44:48.014522Z","steps":["trace[1934680123] 'agreement among raft nodes before linearized reading' (duration: 205.687192ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:48.014272Z","caller":"traceutil/trace.go:171","msg":"trace[1405470688] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"215.875289ms","start":"2026-08-22T18:44:47.798377Z","end":"2026-08-22T18:44:48.014252Z","steps":["trace[1405470688] 'process raft request' (duration: 215.398748ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:48.014592Z","caller":"traceutil/trace.go:171","msg":"trace[1859134252] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:308; }","duration":"130.125025ms","start":"2026-08-22T18:44:47.884437Z","end":"2026-08-22T18:44:48.014562Z","steps":["trace[1859134252] 'agreement among raft nodes before linearized reading' (duration: 129.89826ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:48.014423Z","caller":"traceutil/trace.go:171","msg":"trace[1078755168] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:308; }","duration":"229.157239ms","start":"2026-08-22T18:44:47.785255Z","end":"2026-08-22T18:44:48.014412Z","steps":["trace[1078755168] 'agreement among raft nodes before linearized reading' (duration: 228.733979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.242622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.620112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T18:44:48.24295Z","caller":"traceutil/trace.go:171","msg":"trace[622755664] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:317; }","duration":"229.021139ms","start":"2026-08-22T18:44:48.013907Z","end":"2026-08-22T18:44:48.242929Z","steps":["trace[622755664] 'read index received' (duration: 117.518126ms)","trace[622755664] 'applied index is now lower than readState.Index' (duration: 111.501733ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:44:48.242878Z","caller":"traceutil/trace.go:171","msg":"trace[1309972315] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"251.01263ms","start":"2026-08-22T18:44:47.991818Z","end":"2026-08-22T18:44:48.242831Z","steps":["trace[1309972315] 'process raft request' (duration: 139.518267ms)","trace[1309972315] 'compare' (duration: 110.427357ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:44:48.243101Z","caller":"traceutil/trace.go:171","msg":"trace[1056563934] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"250.703713ms","start":"2026-08-22T18:44:47.992383Z","end":"2026-08-22T18:44:48.243087Z","steps":["trace[1056563934] 'process raft request' (duration: 250.385915ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:48.243257Z","caller":"traceutil/trace.go:171","msg":"trace[1150359103] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"250.286241ms","start":"2026-08-22T18:44:47.992957Z","end":"2026-08-22T18:44:48.243243Z","steps":["trace[1150359103] 'process raft request' (duration: 249.910002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.243483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.754516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"warn","ts":"2026-08-22T18:44:48.243489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.660924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-08-22T18:44:48.24349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.571725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-08-22T18:44:48.243565Z","caller":"traceutil/trace.go:171","msg":"trace[1313482589] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:311; }","duration":"230.688788ms","start":"2026-08-22T18:44:48.012858Z","end":"2026-08-22T18:44:48.243547Z","steps":["trace[1313482589] 'agreement among raft nodes before linearized reading' (duration: 230.538745ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:48.24354Z","caller":"traceutil/trace.go:171","msg":"trace[2047120845] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:311; }","duration":"229.859869ms","start":"2026-08-22T18:44:48.013667Z","end":"2026-08-22T18:44:48.243527Z","steps":["trace[2047120845] 'agreement among raft nodes before linearized reading' (duration: 229.676495ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:48.243724Z","caller":"traceutil/trace.go:171","msg":"trace[1040851404] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:311; }","duration":"229.766476ms","start":"2026-08-22T18:44:48.01377Z","end":"2026-08-22T18:44:48.243536Z","steps":["trace[1040851404] 'agreement among raft nodes before linearized reading' (duration: 229.601782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.243493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.168966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-22T18:44:48.243878Z","caller":"traceutil/trace.go:171","msg":"trace[619754328] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:311; }","duration":"259.736359ms","start":"2026-08-22T18:44:47.984123Z","end":"2026-08-22T18:44:48.243859Z","steps":["trace[619754328] 'agreement among raft nodes before linearized reading' (duration: 259.317119ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:48.287052Z","caller":"traceutil/trace.go:171","msg":"trace[781778482] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"268.152587ms","start":"2026-08-22T18:44:48.018877Z","end":"2026-08-22T18:44:48.28703Z","steps":["trace[781778482] 'process raft request' (duration: 267.891391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.28718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.498764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-08-22T18:44:48.287197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.49481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-22T18:44:48.287228Z","caller":"traceutil/trace.go:171","msg":"trace[645762453] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:315; }","duration":"101.640827ms","start":"2026-08-22T18:44:48.185574Z","end":"2026-08-22T18:44:48.287215Z","steps":["trace[645762453] 'agreement among raft nodes before linearized reading' (duration: 101.491233ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:48.287247Z","caller":"traceutil/trace.go:171","msg":"trace[410827113] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:315; }","duration":"151.610513ms","start":"2026-08-22T18:44:48.135621Z","end":"2026-08-22T18:44:48.287232Z","steps":["trace[410827113] 'agreement among raft nodes before linearized reading' (duration: 151.511911ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:48.28708Z","caller":"traceutil/trace.go:171","msg":"trace[85834657] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"267.373709ms","start":"2026-08-22T18:44:48.019669Z","end":"2026-08-22T18:44:48.287042Z","steps":["trace[85834657] 'process raft request' (duration: 267.222465ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:48.287365Z","caller":"traceutil/trace.go:171","msg":"trace[1748993428] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"267.477761ms","start":"2026-08-22T18:44:48.019834Z","end":"2026-08-22T18:44:48.287311Z","steps":["trace[1748993428] 'process raft request' (duration: 267.112242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.287441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.245327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-22T18:44:48.287475Z","caller":"traceutil/trace.go:171","msg":"trace[132629065] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:315; }","duration":"202.384821ms","start":"2026-08-22T18:44:48.085081Z","end":"2026-08-22T18:44:48.287466Z","steps":["trace[132629065] 'agreement among raft nodes before linearized reading' (duration: 202.319619ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:48.287354Z","caller":"traceutil/trace.go:171","msg":"trace[1580789541] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"266.469436ms","start":"2026-08-22T18:44:48.020723Z","end":"2026-08-22T18:44:48.287192Z","steps":["trace[1580789541] 'process raft request' (duration: 266.246071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.287603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.250821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-22T18:44:48.28764Z","caller":"traceutil/trace.go:171","msg":"trace[2019674475] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:315; }","duration":"253.311251ms","start":"2026-08-22T18:44:48.034319Z","end":"2026-08-22T18:44:48.28763Z","steps":["trace[2019674475] 'agreement among raft nodes before linearized reading' (duration: 253.2476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.287665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.161747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7102"} {"level":"info","ts":"2026-08-22T18:44:48.287722Z","caller":"traceutil/trace.go:171","msg":"trace[1961284877] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:315; }","duration":"273.836133ms","start":"2026-08-22T18:44:48.013866Z","end":"2026-08-22T18:44:48.287702Z","steps":["trace[1961284877] 'agreement among raft nodes before linearized reading' (duration: 273.746131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.287863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.753724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4660"} {"level":"info","ts":"2026-08-22T18:44:48.287964Z","caller":"traceutil/trace.go:171","msg":"trace[1607799727] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:315; }","duration":"271.878876ms","start":"2026-08-22T18:44:48.016072Z","end":"2026-08-22T18:44:48.287951Z","steps":["trace[1607799727] 'agreement among raft nodes before linearized reading' (duration: 271.699132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.288091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.856254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-08-22T18:44:48.288141Z","caller":"traceutil/trace.go:171","msg":"trace[640199026] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:315; }","duration":"273.955396ms","start":"2026-08-22T18:44:48.014176Z","end":"2026-08-22T18:44:48.288132Z","steps":["trace[640199026] 'agreement among raft nodes before linearized reading' (duration: 273.880754ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.594559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.614487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T18:44:48.594824Z","caller":"traceutil/trace.go:171","msg":"trace[522089831] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"346.771621ms","start":"2026-08-22T18:44:48.248008Z","end":"2026-08-22T18:44:48.59478Z","steps":["trace[522089831] 'process raft request' (duration: 231.656711ms)","trace[522089831] 'compare' (duration: 113.86873ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:44:48.595026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.24799Z","time spent":"346.934485ms","remote":"127.0.0.1:56148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:44:48.595728Z","caller":"traceutil/trace.go:171","msg":"trace[178458573] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:324; }","duration":"308.700978ms","start":"2026-08-22T18:44:48.287009Z","end":"2026-08-22T18:44:48.59571Z","steps":["trace[178458573] 'read index received' (duration: 192.573734ms)","trace[178458573] 'applied index is now lower than readState.Index' (duration: 115.938169ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:44:48.596008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.286833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-08-22T18:44:48.596058Z","caller":"traceutil/trace.go:171","msg":"trace[1829350432] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:317; }","duration":"347.365795ms","start":"2026-08-22T18:44:48.24868Z","end":"2026-08-22T18:44:48.596046Z","steps":["trace[1829350432] 'agreement among raft nodes before linearized reading' (duration: 347.129409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.59609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.24867Z","time spent":"347.412747ms","remote":"127.0.0.1:56136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-22T18:44:48.596081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.276956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"warn","ts":"2026-08-22T18:44:48.596081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.252479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-22T18:44:48.596166Z","caller":"traceutil/trace.go:171","msg":"trace[908395687] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:317; }","duration":"348.40152ms","start":"2026-08-22T18:44:48.247743Z","end":"2026-08-22T18:44:48.596145Z","steps":["trace[908395687] 'agreement among raft nodes before linearized reading' (duration: 348.274467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.596215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.247721Z","time spent":"348.485132ms","remote":"127.0.0.1:56136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-22T18:44:48.596231Z","caller":"traceutil/trace.go:171","msg":"trace[265844303] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:317; }","duration":"311.414263ms","start":"2026-08-22T18:44:48.284763Z","end":"2026-08-22T18:44:48.596178Z","steps":["trace[265844303] 'agreement among raft nodes before linearized reading' (duration: 311.010743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.596323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.284742Z","time spent":"311.527696ms","remote":"127.0.0.1:56148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-08-22T18:44:48.596358Z","caller":"traceutil/trace.go:171","msg":"trace[569954765] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"344.689511ms","start":"2026-08-22T18:44:48.251658Z","end":"2026-08-22T18:44:48.596347Z","steps":["trace[569954765] 'process raft request' (duration: 343.033021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.59608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.156084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"warn","ts":"2026-08-22T18:44:48.596673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.251647Z","time spent":"344.729252ms","remote":"127.0.0.1:56276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:44:48.596635Z","caller":"traceutil/trace.go:171","msg":"trace[761028500] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:317; }","duration":"348.766288ms","start":"2026-08-22T18:44:48.247851Z","end":"2026-08-22T18:44:48.596618Z","steps":["trace[761028500] 'agreement among raft nodes before linearized reading' (duration: 348.128433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:48.596859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.247705Z","time spent":"349.127966ms","remote":"127.0.0.1:56136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-22T18:44:49.511551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.364025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T18:44:49.511729Z","caller":"traceutil/trace.go:171","msg":"trace[2041395] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"1.218816325s","start":"2026-08-22T18:44:48.292882Z","end":"2026-08-22T18:44:49.511698Z","steps":["trace[2041395] 'process raft request' (duration: 403.242415ms)","trace[2041395] 'compare' (duration: 814.848843ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:44:49.511845Z","caller":"traceutil/trace.go:171","msg":"trace[589165345] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"908.020215ms","start":"2026-08-22T18:44:48.603816Z","end":"2026-08-22T18:44:49.511836Z","steps":["trace[589165345] 'process raft request' (duration: 907.992325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.511843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.292864Z","time spent":"1.218925347s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:44:49.511879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.603803Z","time spent":"908.059627ms","remote":"127.0.0.1:56420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:44:49.512056Z","caller":"traceutil/trace.go:171","msg":"trace[739898530] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"1.217537044s","start":"2026-08-22T18:44:48.294513Z","end":"2026-08-22T18:44:49.51205Z","steps":["trace[739898530] 'process raft request' (duration: 1.217129635s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.512084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.294501Z","time spent":"1.217570615s","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:44:49.5122Z","caller":"traceutil/trace.go:171","msg":"trace[141549519] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"911.978442ms","start":"2026-08-22T18:44:48.600215Z","end":"2026-08-22T18:44:49.512193Z","steps":["trace[141549519] 'process raft request' (duration: 911.563672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.512246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.600187Z","time spent":"912.026192ms","remote":"127.0.0.1:56148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:44:49.512254Z","caller":"traceutil/trace.go:171","msg":"trace[379850971] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"1.216789696s","start":"2026-08-22T18:44:48.295448Z","end":"2026-08-22T18:44:49.512238Z","steps":["trace[379850971] 'process raft request' (duration: 1.216235193s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.512338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.29544Z","time spent":"1.216852117s","remote":"127.0.0.1:56132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:44:49.512507Z","caller":"traceutil/trace.go:171","msg":"trace[381670453] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"1.216904659s","start":"2026-08-22T18:44:48.295591Z","end":"2026-08-22T18:44:49.512496Z","steps":["trace[381670453] 'process raft request' (duration: 1.216120821s)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:49.512544Z","caller":"traceutil/trace.go:171","msg":"trace[1883035794] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:326; }","duration":"916.761996ms","start":"2026-08-22T18:44:48.595774Z","end":"2026-08-22T18:44:49.512536Z","steps":["trace[1883035794] 'read index received' (duration: 100.390537ms)","trace[1883035794] 'applied index is now lower than readState.Index' (duration: 816.370509ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:44:49.512568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.295585Z","time spent":"1.21695345s","remote":"127.0.0.1:56048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:44:49.512706Z","caller":"traceutil/trace.go:171","msg":"trace[531897659] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"1.215310631s","start":"2026-08-22T18:44:48.297389Z","end":"2026-08-22T18:44:49.5127Z","steps":["trace[531897659] 'process raft request' (duration: 1.214353818s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.512743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.297372Z","time spent":"1.215349902s","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:44:49.512836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078028891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-22T18:44:49.512855Z","caller":"traceutil/trace.go:171","msg":"trace[1519610550] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:324; }","duration":"1.078097772s","start":"2026-08-22T18:44:48.434751Z","end":"2026-08-22T18:44:49.512849Z","steps":["trace[1519610550] 'agreement among raft nodes before linearized reading' (duration: 1.078053951s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.512874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.434724Z","time spent":"1.078145474s","remote":"127.0.0.1:56148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-08-22T18:44:49.512947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08054691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:44:49.512962Z","caller":"traceutil/trace.go:171","msg":"trace[1446676348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:324; }","duration":"1.080621992s","start":"2026-08-22T18:44:48.432335Z","end":"2026-08-22T18:44:49.512957Z","steps":["trace[1446676348] 'agreement among raft nodes before linearized reading' (duration: 1.080598081s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.512977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.432308Z","time spent":"1.080665103s","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T18:44:49.513001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218523407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-08-22T18:44:49.513058Z","caller":"traceutil/trace.go:171","msg":"trace[2097752911] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:324; }","duration":"1.21860745s","start":"2026-08-22T18:44:48.294436Z","end":"2026-08-22T18:44:49.513043Z","steps":["trace[2097752911] 'agreement among raft nodes before linearized reading' (duration: 1.218512907s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.513088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.225671ms","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-22T18:44:49.513105Z","caller":"traceutil/trace.go:171","msg":"trace[1121920166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:324; }","duration":"249.323633ms","start":"2026-08-22T18:44:49.263776Z","end":"2026-08-22T18:44:49.5131Z","steps":["trace[1121920166] 'agreement among raft nodes before linearized reading' (duration: 249.289712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.513097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.294426Z","time spent":"1.218661331s","remote":"127.0.0.1:56136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-22T18:44:49.51335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128981712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-22T18:44:49.513393Z","caller":"traceutil/trace.go:171","msg":"trace[1959443668] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:324; }","duration":"1.129064084s","start":"2026-08-22T18:44:48.384315Z","end":"2026-08-22T18:44:49.513379Z","steps":["trace[1959443668] 'agreement among raft nodes before linearized reading' (duration: 1.128989122s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.513431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.384289Z","time spent":"1.129132325s","remote":"127.0.0.1:56148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-08-22T18:44:49.513515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217750628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-08-22T18:44:49.513548Z","caller":"traceutil/trace.go:171","msg":"trace[1339782769] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:324; }","duration":"1.21782878s","start":"2026-08-22T18:44:48.295708Z","end":"2026-08-22T18:44:49.513537Z","steps":["trace[1339782769] 'agreement among raft nodes before linearized reading' (duration: 1.217692027s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.51357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.295688Z","time spent":"1.217876151s","remote":"127.0.0.1:56136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-22T18:44:49.51361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219098142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-08-22T18:44:49.513648Z","caller":"traceutil/trace.go:171","msg":"trace[1783937330] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:324; }","duration":"1.219147793s","start":"2026-08-22T18:44:48.294488Z","end":"2026-08-22T18:44:49.513636Z","steps":["trace[1783937330] 'agreement among raft nodes before linearized reading' (duration: 1.219079221s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.513684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.294483Z","time spent":"1.219192554s","remote":"127.0.0.1:56136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-22T18:44:49.51369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179021071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-22T18:44:49.515087Z","caller":"traceutil/trace.go:171","msg":"trace[707680564] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:324; }","duration":"1.180423394s","start":"2026-08-22T18:44:48.334635Z","end":"2026-08-22T18:44:49.515058Z","steps":["trace[707680564] 'agreement among raft nodes before linearized reading' (duration: 1.179027571s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.515191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:48.334621Z","time spent":"1.180527616s","remote":"127.0.0.1:56148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-08-22T18:44:49.885863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.127952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T18:44:49.886004Z","caller":"traceutil/trace.go:171","msg":"trace[1605369100] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:333; }","duration":"365.894561ms","start":"2026-08-22T18:44:49.520091Z","end":"2026-08-22T18:44:49.885985Z","steps":["trace[1605369100] 'read index received' (duration: 176.557494ms)","trace[1605369100] 'applied index is now lower than readState.Index' (duration: 189.335967ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:44:49.886174Z","caller":"traceutil/trace.go:171","msg":"trace[824199678] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"366.493226ms","start":"2026-08-22T18:44:49.519619Z","end":"2026-08-22T18:44:49.886112Z","steps":["trace[824199678] 'process raft request' (duration: 177.011606ms)","trace[824199678] 'compare' (duration: 188.960198ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:44:49.886277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.132049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-22T18:44:49.886371Z","caller":"traceutil/trace.go:171","msg":"trace[2065430724] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:325; }","duration":"366.285651ms","start":"2026-08-22T18:44:49.520058Z","end":"2026-08-22T18:44:49.886344Z","steps":["trace[2065430724] 'agreement among raft nodes before linearized reading' (duration: 366.074086ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.886378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:49.519557Z","time spent":"366.707672ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:44:49.886421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:49.520037Z","time spent":"366.374494ms","remote":"127.0.0.1:56148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-08-22T18:44:49.903946Z","caller":"traceutil/trace.go:171","msg":"trace[1334053450] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"383.524621ms","start":"2026-08-22T18:44:49.520391Z","end":"2026-08-22T18:44:49.903916Z","steps":["trace[1334053450] 'process raft request' (duration: 383.296716ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:49.904115Z","caller":"traceutil/trace.go:171","msg":"trace[1615556354] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"379.033014ms","start":"2026-08-22T18:44:49.525068Z","end":"2026-08-22T18:44:49.904101Z","steps":["trace[1615556354] 'process raft request' (duration: 378.998503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.904223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:49.525058Z","time spent":"379.102496ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:44:49.904347Z","caller":"traceutil/trace.go:171","msg":"trace[1482025569] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"383.387048ms","start":"2026-08-22T18:44:49.520921Z","end":"2026-08-22T18:44:49.904308Z","steps":["trace[1482025569] 'process raft request' (duration: 382.876116ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:49.90428Z","caller":"traceutil/trace.go:171","msg":"trace[1498957084] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"382.304112ms","start":"2026-08-22T18:44:49.521924Z","end":"2026-08-22T18:44:49.904228Z","steps":["trace[1498957084] 'process raft request' (duration: 381.937144ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:49.904532Z","caller":"traceutil/trace.go:171","msg":"trace[938209145] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"383.256795ms","start":"2026-08-22T18:44:49.521255Z","end":"2026-08-22T18:44:49.904512Z","steps":["trace[938209145] 'process raft request' (duration: 382.579439ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:49.904412Z","caller":"traceutil/trace.go:171","msg":"trace[473467510] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"380.872148ms","start":"2026-08-22T18:44:49.523511Z","end":"2026-08-22T18:44:49.904383Z","steps":["trace[473467510] 'process raft request' (duration: 380.399717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.904659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:49.521244Z","time spent":"383.348027ms","remote":"127.0.0.1:56224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T18:44:49.904947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:49.523482Z","time spent":"381.404821ms","remote":"127.0.0.1:56384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:44:49.904534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:49.520903Z","time spent":"383.533531ms","remote":"127.0.0.1:56048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:44:49.904401Z","caller":"traceutil/trace.go:171","msg":"trace[767393594] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"380.10274ms","start":"2026-08-22T18:44:49.524288Z","end":"2026-08-22T18:44:49.904391Z","steps":["trace[767393594] 'process raft request' (duration: 379.740572ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.905362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:49.52428Z","time spent":"381.047882ms","remote":"127.0.0.1:56136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:44:49.904141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:49.520376Z","time spent":"383.705356ms","remote":"127.0.0.1:56148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:44:49.906083Z","caller":"traceutil/trace.go:171","msg":"trace[633615897] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"121.774513ms","start":"2026-08-22T18:44:49.784289Z","end":"2026-08-22T18:44:49.906064Z","steps":["trace[633615897] 'process raft request' (duration: 121.381073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.906345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.314944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:44:49.906526Z","caller":"traceutil/trace.go:171","msg":"trace[471841809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:333; }","duration":"385.487288ms","start":"2026-08-22T18:44:49.520967Z","end":"2026-08-22T18:44:49.906455Z","steps":["trace[471841809] 'agreement among raft nodes before linearized reading' (duration: 385.259243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:49.906696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:49.519023Z","time spent":"387.65434ms","remote":"127.0.0.1:55966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T18:44:49.908517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:44:49.521911Z","time spent":"382.705422ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:44:50.125277Z","caller":"traceutil/trace.go:171","msg":"trace[1285172933] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"158.759638ms","start":"2026-08-22T18:44:49.966472Z","end":"2026-08-22T18:44:50.125231Z","steps":["trace[1285172933] 'process raft request' (duration: 98.373915ms)","trace[1285172933] 'compare' (duration: 60.091006ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:44:50.125282Z","caller":"traceutil/trace.go:171","msg":"trace[1435110675] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"158.683575ms","start":"2026-08-22T18:44:49.96657Z","end":"2026-08-22T18:44:50.125253Z","steps":["trace[1435110675] 'process raft request' (duration: 158.615604ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:50.125319Z","caller":"traceutil/trace.go:171","msg":"trace[1722133450] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"158.799518ms","start":"2026-08-22T18:44:49.966484Z","end":"2026-08-22T18:44:50.125283Z","steps":["trace[1722133450] 'process raft request' (duration: 158.646084ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:50.141146Z","caller":"traceutil/trace.go:171","msg":"trace[417876194] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"155.863455ms","start":"2026-08-22T18:44:49.985257Z","end":"2026-08-22T18:44:50.14112Z","steps":["trace[417876194] 'process raft request' (duration: 155.639839ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:50.141437Z","caller":"traceutil/trace.go:171","msg":"trace[1218424034] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"155.013585ms","start":"2026-08-22T18:44:49.986398Z","end":"2026-08-22T18:44:50.141412Z","steps":["trace[1218424034] 'process raft request' (duration: 154.743959ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:50.141345Z","caller":"traceutil/trace.go:171","msg":"trace[17329189] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"155.358513ms","start":"2026-08-22T18:44:49.985871Z","end":"2026-08-22T18:44:50.141229Z","steps":["trace[17329189] 'process raft request' (duration: 155.155667ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:44:50.142262Z","caller":"traceutil/trace.go:171","msg":"trace[1023228090] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"148.09721ms","start":"2026-08-22T18:44:49.994155Z","end":"2026-08-22T18:44:50.142252Z","steps":["trace[1023228090] 'process raft request' (duration: 147.175259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:51.615917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.225887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-08-22T18:44:51.616211Z","caller":"traceutil/trace.go:171","msg":"trace[856785052] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:356; }","duration":"144.583575ms","start":"2026-08-22T18:44:51.471598Z","end":"2026-08-22T18:44:51.616182Z","steps":["trace[856785052] 'range keys from in-memory index tree' (duration: 144.063354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:44:51.915567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.227664ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-08-22T18:45:02.998712Z","caller":"traceutil/trace.go:171","msg":"trace[122101706] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"525.869992ms","start":"2026-08-22T18:45:02.472808Z","end":"2026-08-22T18:45:02.998678Z","steps":["trace[122101706] 'process raft request' (duration: 525.651928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:02.999082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:02.472787Z","time spent":"526.030305ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:03.974052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143327774130460292,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T18:45:03.999134Z","caller":"traceutil/trace.go:171","msg":"trace[553415878] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"1.52310285s","start":"2026-08-22T18:45:02.476002Z","end":"2026-08-22T18:45:03.999105Z","steps":["trace[553415878] 'process raft request' (duration: 1.505328034s)","trace[553415878] 'compare' (duration: 17.327736ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:03.999311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:02.475986Z","time spent":"1.523229872s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:45:03.999385Z","caller":"traceutil/trace.go:171","msg":"trace[1384371326] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:405; }","duration":"525.645589ms","start":"2026-08-22T18:45:03.473698Z","end":"2026-08-22T18:45:03.999343Z","steps":["trace[1384371326] 'read index received' (duration: 507.605378ms)","trace[1384371326] 'applied index is now lower than readState.Index' (duration: 18.038751ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:03.999788Z","caller":"traceutil/trace.go:171","msg":"trace[38789839] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"221.623595ms","start":"2026-08-22T18:45:03.778133Z","end":"2026-08-22T18:45:03.999757Z","steps":["trace[38789839] 'process raft request' (duration: 220.92471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:04.000201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.489328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:04.000263Z","caller":"traceutil/trace.go:171","msg":"trace[1617591530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:394; }","duration":"526.61243ms","start":"2026-08-22T18:45:03.473634Z","end":"2026-08-22T18:45:04.000246Z","steps":["trace[1617591530] 'agreement among raft nodes before linearized reading' (duration: 526.10698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:04.000298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:03.473606Z","time spent":"526.684671ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T18:45:04.000907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.439912ms","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-22T18:45:04.001017Z","caller":"traceutil/trace.go:171","msg":"trace[321353148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:394; }","duration":"284.622657ms","start":"2026-08-22T18:45:03.716366Z","end":"2026-08-22T18:45:04.000988Z","steps":["trace[321353148] 'agreement among raft nodes before linearized reading' (duration: 283.529534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:07.678169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.440701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T18:45:07.678281Z","caller":"traceutil/trace.go:171","msg":"trace[965420176] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"245.48585ms","start":"2026-08-22T18:45:07.432778Z","end":"2026-08-22T18:45:07.678263Z","steps":["trace[965420176] 'read index received' (duration: 58.281µs)","trace[965420176] 'applied index is now lower than readState.Index' (duration: 245.426339ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:07.678384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.602432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:07.678412Z","caller":"traceutil/trace.go:171","msg":"trace[1217806215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"245.687453ms","start":"2026-08-22T18:45:07.432715Z","end":"2026-08-22T18:45:07.678403Z","steps":["trace[1217806215] 'agreement among raft nodes before linearized reading' (duration: 245.594662ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:07.678414Z","caller":"traceutil/trace.go:171","msg":"trace[941029573] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"597.847658ms","start":"2026-08-22T18:45:07.080535Z","end":"2026-08-22T18:45:07.678383Z","steps":["trace[941029573] 'process raft request' (duration: 285.109691ms)","trace[941029573] 'compare' (duration: 312.213216ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:07.678551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:07.080511Z","time spent":"597.970941ms","remote":"127.0.0.1:56420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T18:45:23.347347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.517232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T18:45:23.347713Z","caller":"traceutil/trace.go:171","msg":"trace[64834622] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.069888479s","start":"2026-08-22T18:45:22.277782Z","end":"2026-08-22T18:45:23.34767Z","steps":["trace[64834622] 'process raft request' (duration: 1.069722046s)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:23.347801Z","caller":"traceutil/trace.go:171","msg":"trace[1705661429] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.065410146s","start":"2026-08-22T18:45:22.282374Z","end":"2026-08-22T18:45:23.347784Z","steps":["trace[1705661429] 'process raft request' (duration: 1.065247423s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.347883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.282347Z","time spent":"1.065487768s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:45:23.347882Z","caller":"traceutil/trace.go:171","msg":"trace[1546644371] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.070110353s","start":"2026-08-22T18:45:22.277727Z","end":"2026-08-22T18:45:23.347837Z","steps":["trace[1546644371] 'process raft request' (duration: 229.037601ms)","trace[1546644371] 'compare' (duration: 840.378089ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:23.347903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.277778Z","time spent":"1.070041641s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:45:23.347992Z","caller":"traceutil/trace.go:171","msg":"trace[210881194] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.068768017s","start":"2026-08-22T18:45:22.279213Z","end":"2026-08-22T18:45:23.347981Z","steps":["trace[210881194] 'process raft request' (duration: 1.06835472s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.348035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.279207Z","time spent":"1.068809158s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T18:45:23.348056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.277716Z","time spent":"1.070251705s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:45:23.34811Z","caller":"traceutil/trace.go:171","msg":"trace[1628495458] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.068781317s","start":"2026-08-22T18:45:22.279323Z","end":"2026-08-22T18:45:23.348105Z","steps":["trace[1628495458] 'process raft request' (duration: 1.068273118s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.348139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.279319Z","time spent":"1.068805738s","remote":"127.0.0.1:56450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:45:23.361393Z","caller":"traceutil/trace.go:171","msg":"trace[1029185290] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.035375953s","start":"2026-08-22T18:45:22.325709Z","end":"2026-08-22T18:45:23.361085Z","steps":["trace[1029185290] 'process raft request' (duration: 1.033480288s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.361568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.325702Z","time spent":"1.03579041s","remote":"127.0.0.1:56450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:45:23.362199Z","caller":"traceutil/trace.go:171","msg":"trace[485821313] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.072067507s","start":"2026-08-22T18:45:22.290031Z","end":"2026-08-22T18:45:23.362098Z","steps":["trace[485821313] 'process raft request' (duration: 1.06894405s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.362263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.290004Z","time spent":"1.072218729s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:45:23.362413Z","caller":"traceutil/trace.go:171","msg":"trace[780350739] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.025398489s","start":"2026-08-22T18:45:22.336881Z","end":"2026-08-22T18:45:23.362279Z","steps":["trace[780350739] 'process raft request' (duration: 1.022385424s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.362583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.33687Z","time spent":"1.025622203s","remote":"127.0.0.1:56450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:45:23.362614Z","caller":"traceutil/trace.go:171","msg":"trace[45625406] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.067102635s","start":"2026-08-22T18:45:22.295382Z","end":"2026-08-22T18:45:23.362484Z","steps":["trace[45625406] 'process raft request' (duration: 1.063710703s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.362658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.295364Z","time spent":"1.067270489s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:45:23.363045Z","caller":"traceutil/trace.go:171","msg":"trace[1985169132] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.031484631s","start":"2026-08-22T18:45:22.331538Z","end":"2026-08-22T18:45:23.363022Z","steps":["trace[1985169132] 'process raft request' (duration: 1.027682391s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.363116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.331529Z","time spent":"1.031537922s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:45:23.363418Z","caller":"traceutil/trace.go:171","msg":"trace[1983921741] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.031704094s","start":"2026-08-22T18:45:22.331702Z","end":"2026-08-22T18:45:23.363406Z","steps":["trace[1983921741] 'process raft request' (duration: 1.027541388s)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:23.363518Z","caller":"traceutil/trace.go:171","msg":"trace[2061684502] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.020952998s","start":"2026-08-22T18:45:22.342521Z","end":"2026-08-22T18:45:23.363474Z","steps":["trace[2061684502] 'process raft request' (duration: 1.016765911s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.363563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.331694Z","time spent":"1.031742835s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:23.363898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.342507Z","time spent":"1.0210775s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:45:23.36399Z","caller":"traceutil/trace.go:171","msg":"trace[1138811953] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.050430618s","start":"2026-08-22T18:45:22.313542Z","end":"2026-08-22T18:45:23.363973Z","steps":["trace[1138811953] 'process raft request' (duration: 1.04558488s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.364129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.313522Z","time spent":"1.05048704s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:45:23.364798Z","caller":"traceutil/trace.go:171","msg":"trace[643426572] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.039423316s","start":"2026-08-22T18:45:22.325356Z","end":"2026-08-22T18:45:23.364779Z","steps":["trace[643426572] 'process raft request' (duration: 1.033814834s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.364832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.325343Z","time spent":"1.039470537s","remote":"127.0.0.1:56450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:45:23.364987Z","caller":"traceutil/trace.go:171","msg":"trace[682565689] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:522; }","duration":"1.040615668s","start":"2026-08-22T18:45:22.324326Z","end":"2026-08-22T18:45:23.364942Z","steps":["trace[682565689] 'read index received' (duration: 182.941083ms)","trace[682565689] 'applied index is now lower than readState.Index' (duration: 857.652384ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:23.365296Z","caller":"traceutil/trace.go:171","msg":"trace[1878078277] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"884.275753ms","start":"2026-08-22T18:45:22.481005Z","end":"2026-08-22T18:45:23.36528Z","steps":["trace[1878078277] 'process raft request' (duration: 879.254502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.365363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.480973Z","time spent":"884.348866ms","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T18:45:23.365615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041283281s","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-22T18:45:23.365648Z","caller":"traceutil/trace.go:171","msg":"trace[952364995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"1.041341162s","start":"2026-08-22T18:45:22.324292Z","end":"2026-08-22T18:45:23.365633Z","steps":["trace[952364995] 'agreement among raft nodes before linearized reading' (duration: 1.0407363s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.365667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.324272Z","time spent":"1.041389863s","remote":"127.0.0.1:56210","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":"info","ts":"2026-08-22T18:45:23.366018Z","caller":"traceutil/trace.go:171","msg":"trace[1036078087] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.002601478s","start":"2026-08-22T18:45:22.362698Z","end":"2026-08-22T18:45:23.365299Z","steps":["trace[1036078087] 'process raft request' (duration: 996.758602ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.366139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.362674Z","time spent":"1.003360653s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T18:45:23.399813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.471946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-22T18:45:23.399882Z","caller":"traceutil/trace.go:171","msg":"trace[31685656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"209.602318ms","start":"2026-08-22T18:45:23.190265Z","end":"2026-08-22T18:45:23.399867Z","steps":["trace[31685656] 'agreement among raft nodes before linearized reading' (duration: 209.405305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.399872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.420885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:23.399988Z","caller":"traceutil/trace.go:171","msg":"trace[590130977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"969.570178ms","start":"2026-08-22T18:45:22.430393Z","end":"2026-08-22T18:45:23.399963Z","steps":["trace[590130977] 'agreement among raft nodes before linearized reading' (duration: 969.421045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:23.400032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:22.430368Z","time spent":"969.652589ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T18:45:24.555029Z","caller":"traceutil/trace.go:171","msg":"trace[1083661874] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.114198182s","start":"2026-08-22T18:45:23.440795Z","end":"2026-08-22T18:45:24.554993Z","steps":["trace[1083661874] 'process raft request' (duration: 1.100636026s)","trace[1083661874] 'compare' (duration: 12.972896ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:24.555183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:23.440773Z","time spent":"1.114342065s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T18:45:24.555421Z","caller":"traceutil/trace.go:171","msg":"trace[2127418126] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.111074144s","start":"2026-08-22T18:45:23.444334Z","end":"2026-08-22T18:45:24.555408Z","steps":["trace[2127418126] 'process raft request' (duration: 1.110480913s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:24.555503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:23.444308Z","time spent":"1.111144666s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:45:24.555627Z","caller":"traceutil/trace.go:171","msg":"trace[1671346637] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.101172044s","start":"2026-08-22T18:45:23.454404Z","end":"2026-08-22T18:45:24.555576Z","steps":["trace[1671346637] 'process raft request' (duration: 1.100578363s)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:24.555699Z","caller":"traceutil/trace.go:171","msg":"trace[928262162] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.045680081s","start":"2026-08-22T18:45:23.509998Z","end":"2026-08-22T18:45:24.555678Z","steps":["trace[928262162] 'process raft request' (duration: 1.045168681s)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:24.555685Z","caller":"traceutil/trace.go:171","msg":"trace[1188572290] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:550; }","duration":"351.679099ms","start":"2026-08-22T18:45:24.20398Z","end":"2026-08-22T18:45:24.555659Z","steps":["trace[1188572290] 'read index received' (duration: 337.3736ms)","trace[1188572290] 'applied index is now lower than readState.Index' (duration: 14.304669ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:24.55578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:23.509971Z","time spent":"1.045773672s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:24.555857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:23.454382Z","time spent":"1.101345367s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:24.555927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.11366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T18:45:24.555974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.966956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-22T18:45:24.556012Z","caller":"traceutil/trace.go:171","msg":"trace[1306771904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"125.227622ms","start":"2026-08-22T18:45:24.430743Z","end":"2026-08-22T18:45:24.55597Z","steps":["trace[1306771904] 'agreement among raft nodes before linearized reading' (duration: 125.126539ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:24.556026Z","caller":"traceutil/trace.go:171","msg":"trace[582708000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"352.086138ms","start":"2026-08-22T18:45:24.203932Z","end":"2026-08-22T18:45:24.556018Z","steps":["trace[582708000] 'agreement among raft nodes before linearized reading' (duration: 351.902443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:24.556078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:24.203908Z","time spent":"352.159869ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T18:45:24.947142Z","caller":"traceutil/trace.go:171","msg":"trace[741632010] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"104.721589ms","start":"2026-08-22T18:45:24.842399Z","end":"2026-08-22T18:45:24.947121Z","steps":["trace[741632010] 'process raft request' (duration: 104.668068ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:24.947226Z","caller":"traceutil/trace.go:171","msg":"trace[2087436425] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"120.17199ms","start":"2026-08-22T18:45:24.827012Z","end":"2026-08-22T18:45:24.947184Z","steps":["trace[2087436425] 'process raft request' (duration: 115.128569ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:25.286758Z","caller":"traceutil/trace.go:171","msg":"trace[501009131] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"127.595399ms","start":"2026-08-22T18:45:25.159133Z","end":"2026-08-22T18:45:25.286729Z","steps":["trace[501009131] 'process raft request' (duration: 127.231163ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:25.286836Z","caller":"traceutil/trace.go:171","msg":"trace[1699184624] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"151.564641ms","start":"2026-08-22T18:45:25.13523Z","end":"2026-08-22T18:45:25.286794Z","steps":["trace[1699184624] 'process raft request' (duration: 125.290348ms)","trace[1699184624] 'compare' (duration: 25.619622ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:29.347963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.067517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T18:45:29.34809Z","caller":"traceutil/trace.go:171","msg":"trace[517472075] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"307.855487ms","start":"2026-08-22T18:45:29.040216Z","end":"2026-08-22T18:45:29.348072Z","steps":["trace[517472075] 'process raft request' (duration: 18.557046ms)","trace[517472075] 'compare' (duration: 288.753072ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:29.348145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:29.040192Z","time spent":"307.935528ms","remote":"127.0.0.1:56254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T18:45:29.931152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143327774130460674,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T18:45:30.182698Z","caller":"traceutil/trace.go:171","msg":"trace[1351272299] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"754.296618ms","start":"2026-08-22T18:45:29.428366Z","end":"2026-08-22T18:45:30.182662Z","steps":["trace[1351272299] 'process raft request' (duration: 669.323295ms)","trace[1351272299] 'compare' (duration: 84.607857ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:30.182851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:29.428335Z","time spent":"754.431231ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:45:30.190322Z","caller":"traceutil/trace.go:171","msg":"trace[1654521826] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"759.359886ms","start":"2026-08-22T18:45:29.430932Z","end":"2026-08-22T18:45:30.190292Z","steps":["trace[1654521826] 'read index received' (duration: 666.782181ms)","trace[1654521826] 'applied index is now lower than readState.Index' (duration: 92.575965ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:30.190445Z","caller":"traceutil/trace.go:171","msg":"trace[1646508276] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"413.809417ms","start":"2026-08-22T18:45:29.776607Z","end":"2026-08-22T18:45:30.190417Z","steps":["trace[1646508276] 'process raft request' (duration: 413.233217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:30.190481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.536889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:30.190518Z","caller":"traceutil/trace.go:171","msg":"trace[1800986690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"759.637171ms","start":"2026-08-22T18:45:29.430868Z","end":"2026-08-22T18:45:30.190505Z","steps":["trace[1800986690] 'agreement among raft nodes before linearized reading' (duration: 759.546679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:30.190553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:29.43085Z","time spent":"759.694771ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T18:45:30.190595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:29.776586Z","time spent":"413.925529ms","remote":"127.0.0.1:56054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:30.190928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.084154ms","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-22T18:45:30.190963Z","caller":"traceutil/trace.go:171","msg":"trace[2060888509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"750.151064ms","start":"2026-08-22T18:45:29.440801Z","end":"2026-08-22T18:45:30.190952Z","steps":["trace[2060888509] 'agreement among raft nodes before linearized reading' (duration: 749.978282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:30.19099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:29.440783Z","time spent":"750.199115ms","remote":"127.0.0.1:56210","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-22T18:45:30.19109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.035877ms","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-22T18:45:30.191134Z","caller":"traceutil/trace.go:171","msg":"trace[262644157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"744.104278ms","start":"2026-08-22T18:45:29.447019Z","end":"2026-08-22T18:45:30.191123Z","steps":["trace[262644157] 'agreement among raft nodes before linearized reading' (duration: 743.993247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:30.191169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:29.44701Z","time spent":"744.152509ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T18:45:31.724841Z","caller":"traceutil/trace.go:171","msg":"trace[368089146] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"355.832972ms","start":"2026-08-22T18:45:31.368979Z","end":"2026-08-22T18:45:31.724812Z","steps":["trace[368089146] 'read index received' (duration: 355.083548ms)","trace[368089146] 'applied index is now lower than readState.Index' (duration: 748.464µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:31.724934Z","caller":"traceutil/trace.go:171","msg":"trace[2088813003] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"461.571516ms","start":"2026-08-22T18:45:31.263321Z","end":"2026-08-22T18:45:31.724892Z","steps":["trace[2088813003] 'process raft request' (duration: 460.917114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:31.725057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.063715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.11\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-22T18:45:31.725088Z","caller":"traceutil/trace.go:171","msg":"trace[2054096187] range","detail":"{range_begin:/registry/masterleases/199.19.213.11; range_end:; response_count:1; response_revision:607; }","duration":"356.157997ms","start":"2026-08-22T18:45:31.368921Z","end":"2026-08-22T18:45:31.725079Z","steps":["trace[2054096187] 'agreement among raft nodes before linearized reading' (duration: 356.024155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:31.725117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:31.368898Z","time spent":"356.212509ms","remote":"127.0.0.1:56000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.11\" "} {"level":"warn","ts":"2026-08-22T18:45:31.725096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:31.263285Z","time spent":"461.719919ms","remote":"127.0.0.1:56054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:31.72532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.512777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xz8qw\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-08-22T18:45:31.725405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.910057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:31.72545Z","caller":"traceutil/trace.go:171","msg":"trace[322853483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"293.978479ms","start":"2026-08-22T18:45:31.431462Z","end":"2026-08-22T18:45:31.725441Z","steps":["trace[322853483] 'agreement among raft nodes before linearized reading' (duration: 293.902018ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:31.725402Z","caller":"traceutil/trace.go:171","msg":"trace[1129859603] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xz8qw; range_end:; response_count:1; response_revision:607; }","duration":"321.623438ms","start":"2026-08-22T18:45:31.403756Z","end":"2026-08-22T18:45:31.725379Z","steps":["trace[1129859603] 'agreement among raft nodes before linearized reading' (duration: 321.385894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:31.726167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:31.403746Z","time spent":"322.408732ms","remote":"127.0.0.1:56142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-xz8qw\" "} {"level":"warn","ts":"2026-08-22T18:45:32.651119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.177823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T18:45:32.651265Z","caller":"traceutil/trace.go:171","msg":"trace[1193055207] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"910.152617ms","start":"2026-08-22T18:45:31.741088Z","end":"2026-08-22T18:45:32.651241Z","steps":["trace[1193055207] 'process raft request' (duration: 212.74765ms)","trace[1193055207] 'compare' (duration: 696.927299ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:32.651334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:31.741056Z","time spent":"910.246779ms","remote":"127.0.0.1:56000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:45:32.656611Z","caller":"traceutil/trace.go:171","msg":"trace[754717918] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"437.597093ms","start":"2026-08-22T18:45:32.218993Z","end":"2026-08-22T18:45:32.65659Z","steps":["trace[754717918] 'read index received' (duration: 437.348089ms)","trace[754717918] 'applied index is now lower than readState.Index' (duration: 248.244µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:32.656673Z","caller":"traceutil/trace.go:171","msg":"trace[165500853] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"905.412374ms","start":"2026-08-22T18:45:31.751234Z","end":"2026-08-22T18:45:32.656647Z","steps":["trace[165500853] 'process raft request' (duration: 905.18267ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:32.656711Z","caller":"traceutil/trace.go:171","msg":"trace[1877088946] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"872.32263ms","start":"2026-08-22T18:45:31.784368Z","end":"2026-08-22T18:45:32.656691Z","steps":["trace[1877088946] 'process raft request' (duration: 872.178927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:32.656795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:31.784357Z","time spent":"872.401621ms","remote":"127.0.0.1:56290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T18:45:32.656845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:31.751193Z","time spent":"905.553946ms","remote":"127.0.0.1:56142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:32.656857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.832397ms","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-22T18:45:32.656895Z","caller":"traceutil/trace.go:171","msg":"trace[728773166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"437.930068ms","start":"2026-08-22T18:45:32.218954Z","end":"2026-08-22T18:45:32.656884Z","steps":["trace[728773166] 'agreement among raft nodes before linearized reading' (duration: 437.748065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:32.656989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:32.218935Z","time spent":"438.029451ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T18:45:32.657025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.02072ms","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-22T18:45:32.657002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.630624ms","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-22T18:45:32.657086Z","caller":"traceutil/trace.go:171","msg":"trace[1486590803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"438.108541ms","start":"2026-08-22T18:45:32.218955Z","end":"2026-08-22T18:45:32.657063Z","steps":["trace[1486590803] 'agreement among raft nodes before linearized reading' (duration: 437.947888ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:32.657104Z","caller":"traceutil/trace.go:171","msg":"trace[1770020104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"437.887158ms","start":"2026-08-22T18:45:32.219197Z","end":"2026-08-22T18:45:32.657084Z","steps":["trace[1770020104] 'agreement among raft nodes before linearized reading' (duration: 437.564042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:32.657156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:32.219192Z","time spent":"437.954609ms","remote":"127.0.0.1:56210","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-22T18:45:32.657174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.964632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:32.657214Z","caller":"traceutil/trace.go:171","msg":"trace[1820788615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"227.036124ms","start":"2026-08-22T18:45:32.430167Z","end":"2026-08-22T18:45:32.657203Z","steps":["trace[1820788615] 'agreement among raft nodes before linearized reading' (duration: 226.979173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:32.657112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:32.218935Z","time spent":"438.169763ms","remote":"127.0.0.1:56210","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-22T18:45:34.603316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.794794077s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T18:45:34.603694Z","caller":"traceutil/trace.go:171","msg":"trace[1249932119] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.936616331s","start":"2026-08-22T18:45:32.666981Z","end":"2026-08-22T18:45:34.603598Z","steps":["trace[1249932119] 'process raft request' (duration: 141.417257ms)","trace[1249932119] 'compare' (duration: 1.793900312s)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:34.603764Z","caller":"traceutil/trace.go:171","msg":"trace[449927995] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.936005121s","start":"2026-08-22T18:45:32.667612Z","end":"2026-08-22T18:45:34.603617Z","steps":["trace[449927995] 'process raft request' (duration: 1.93593518s)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:34.603968Z","caller":"traceutil/trace.go:171","msg":"trace[300147787] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.936312357s","start":"2026-08-22T18:45:32.667614Z","end":"2026-08-22T18:45:34.603927Z","steps":["trace[300147787] 'process raft request' (duration: 1.935841589s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:34.604024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:32.666958Z","time spent":"1.936871676s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:34.604274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:32.667605Z","time spent":"1.936597401s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:34.604283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:32.667592Z","time spent":"1.936493899s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:34.935395Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143327774130460709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T18:45:35.436261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143327774130460709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T18:45:35.690536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.455811ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:35.690696Z","caller":"traceutil/trace.go:171","msg":"trace[1096578318] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:614; }","duration":"666.636194ms","start":"2026-08-22T18:45:35.024038Z","end":"2026-08-22T18:45:35.690674Z","steps":["trace[1096578318] 'range keys from in-memory index tree' (duration: 666.434771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:35.692619Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.606137084s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T18:45:35.699668Z","caller":"traceutil/trace.go:171","msg":"trace[393571856] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"2.616077853s","start":"2026-08-22T18:45:33.083559Z","end":"2026-08-22T18:45:35.699637Z","steps":["trace[393571856] 'process raft request' (duration: 2.612735446s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:35.699898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:33.083537Z","time spent":"2.616210676s","remote":"127.0.0.1:56054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:45:35.712064Z","caller":"traceutil/trace.go:171","msg":"trace[63852332] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"1.276795341s","start":"2026-08-22T18:45:34.435168Z","end":"2026-08-22T18:45:35.711963Z","steps":["trace[63852332] 'read index received' (duration: 1.25785894s)","trace[63852332] 'applied index is now lower than readState.Index' (duration: 18.934741ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:35.712236Z","caller":"traceutil/trace.go:171","msg":"trace[1685030665] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"664.856443ms","start":"2026-08-22T18:45:35.047364Z","end":"2026-08-22T18:45:35.712221Z","steps":["trace[1685030665] 'process raft request' (duration: 664.425626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:35.712369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:35.04732Z","time spent":"664.948825ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:35.712394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011527913s","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-22T18:45:35.712447Z","caller":"traceutil/trace.go:171","msg":"trace[1910646199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"1.011620635s","start":"2026-08-22T18:45:34.700811Z","end":"2026-08-22T18:45:35.712432Z","steps":["trace[1910646199] 'agreement among raft nodes before linearized reading' (duration: 1.011444452s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:35.712475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:34.700789Z","time spent":"1.011678765s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T18:45:35.712581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277412681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:35.712609Z","caller":"traceutil/trace.go:171","msg":"trace[599898021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"1.277479933s","start":"2026-08-22T18:45:34.435119Z","end":"2026-08-22T18:45:35.712599Z","steps":["trace[599898021] 'agreement among raft nodes before linearized reading' (duration: 1.277414771s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:35.712634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:34.435096Z","time spent":"1.277531443s","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T18:45:36.793352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.997067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-08-22T18:45:36.793352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.615946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-22T18:45:36.793435Z","caller":"traceutil/trace.go:171","msg":"trace[1073733160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"184.157849ms","start":"2026-08-22T18:45:36.609258Z","end":"2026-08-22T18:45:36.793416Z","steps":["trace[1073733160] 'range keys from in-memory index tree' (duration: 183.838654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:36.793394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.793719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-22T18:45:36.79351Z","caller":"traceutil/trace.go:171","msg":"trace[729353615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"181.949781ms","start":"2026-08-22T18:45:36.611536Z","end":"2026-08-22T18:45:36.793486Z","steps":["trace[729353615] 'range keys from in-memory index tree' (duration: 181.615066ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:36.793435Z","caller":"traceutil/trace.go:171","msg":"trace[1515476053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"181.779378ms","start":"2026-08-22T18:45:36.611641Z","end":"2026-08-22T18:45:36.793421Z","steps":["trace[1515476053] 'range keys from in-memory index tree' (duration: 181.439933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:38.114821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.271339ms","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-22T18:45:38.114923Z","caller":"traceutil/trace.go:171","msg":"trace[957523506] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:639; }","duration":"185.444912ms","start":"2026-08-22T18:45:37.92946Z","end":"2026-08-22T18:45:38.114905Z","steps":["trace[957523506] 'range keys from in-memory index tree' (duration: 185.144746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:38.115131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.602266ms","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-22T18:45:38.115259Z","caller":"traceutil/trace.go:171","msg":"trace[2130963667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"380.830149ms","start":"2026-08-22T18:45:37.734396Z","end":"2026-08-22T18:45:38.115227Z","steps":["trace[2130963667] 'range keys from in-memory index tree' (duration: 380.330512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:38.115384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:37.734373Z","time spent":"380.971082ms","remote":"127.0.0.1:56210","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":"info","ts":"2026-08-22T18:45:42.045235Z","caller":"traceutil/trace.go:171","msg":"trace[251996639] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"259.461329ms","start":"2026-08-22T18:45:41.78574Z","end":"2026-08-22T18:45:42.045201Z","steps":["trace[251996639] 'process raft request' (duration: 259.241885ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:42.237421Z","caller":"traceutil/trace.go:171","msg":"trace[1823631871] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:730; }","duration":"140.704637ms","start":"2026-08-22T18:45:42.096682Z","end":"2026-08-22T18:45:42.237386Z","steps":["trace[1823631871] 'read index received' (duration: 139.981606ms)","trace[1823631871] 'applied index is now lower than readState.Index' (duration: 721.221µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:42.23746Z","caller":"traceutil/trace.go:171","msg":"trace[1337881041] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"258.690527ms","start":"2026-08-22T18:45:41.978731Z","end":"2026-08-22T18:45:42.237422Z","steps":["trace[1337881041] 'process raft request' (duration: 257.892984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:42.237829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.104413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:42.238039Z","caller":"traceutil/trace.go:171","msg":"trace[1502004958] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:703; }","duration":"141.394728ms","start":"2026-08-22T18:45:42.096616Z","end":"2026-08-22T18:45:42.23801Z","steps":["trace[1502004958] 'agreement among raft nodes before linearized reading' (duration: 140.843599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:42.253892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.560179ms","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-22T18:45:42.254002Z","caller":"traceutil/trace.go:171","msg":"trace[1926905553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:704; }","duration":"115.729201ms","start":"2026-08-22T18:45:42.138257Z","end":"2026-08-22T18:45:42.253987Z","steps":["trace[1926905553] 'agreement among raft nodes before linearized reading' (duration: 115.474527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:42.255056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.851319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-08-22T18:45:42.255184Z","caller":"traceutil/trace.go:171","msg":"trace[17250026] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:704; }","duration":"101.002412ms","start":"2026-08-22T18:45:42.154124Z","end":"2026-08-22T18:45:42.255127Z","steps":["trace[17250026] 'agreement among raft nodes before linearized reading' (duration: 100.497363ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:42.255237Z","caller":"traceutil/trace.go:171","msg":"trace[567043578] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"135.753967ms","start":"2026-08-22T18:45:42.119416Z","end":"2026-08-22T18:45:42.25517Z","steps":["trace[567043578] 'process raft request' (duration: 133.915607ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:44.119897Z","caller":"traceutil/trace.go:171","msg":"trace[1739020644] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"162.233982ms","start":"2026-08-22T18:45:43.957635Z","end":"2026-08-22T18:45:44.119869Z","steps":["trace[1739020644] 'process raft request' (duration: 162.07513ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:44.122322Z","caller":"traceutil/trace.go:171","msg":"trace[1085398091] transaction","detail":"{read_only:false; number_of_response:0; response_revision:725; }","duration":"163.514443ms","start":"2026-08-22T18:45:43.958793Z","end":"2026-08-22T18:45:44.122308Z","steps":["trace[1085398091] 'process raft request' (duration: 163.444241ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:44.52604Z","caller":"traceutil/trace.go:171","msg":"trace[1723536825] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"253.21213ms","start":"2026-08-22T18:45:44.272796Z","end":"2026-08-22T18:45:44.526008Z","steps":["trace[1723536825] 'process raft request' (duration: 252.940346ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:44.932455Z","caller":"traceutil/trace.go:171","msg":"trace[557143924] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:755; }","duration":"304.963274ms","start":"2026-08-22T18:45:44.627462Z","end":"2026-08-22T18:45:44.932425Z","steps":["trace[557143924] 'read index received' (duration: 221.417681ms)","trace[557143924] 'applied index is now lower than readState.Index' (duration: 83.543863ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:44.932815Z","caller":"traceutil/trace.go:171","msg":"trace[954764232] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"487.942705ms","start":"2026-08-22T18:45:44.444851Z","end":"2026-08-22T18:45:44.932793Z","steps":["trace[954764232] 'process raft request' (duration: 487.421856ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:44.932884Z","caller":"traceutil/trace.go:171","msg":"trace[87052914] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"465.096118ms","start":"2026-08-22T18:45:44.467701Z","end":"2026-08-22T18:45:44.932797Z","steps":["trace[87052914] 'process raft request' (duration: 464.649211ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:44.933003Z","caller":"traceutil/trace.go:171","msg":"trace[2120411353] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"657.401339ms","start":"2026-08-22T18:45:44.275313Z","end":"2026-08-22T18:45:44.932714Z","steps":["trace[2120411353] 'process raft request' (duration: 573.662634ms)","trace[2120411353] 'compare' (duration: 83.103165ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:44.933056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:44.444823Z","time spent":"488.127678ms","remote":"127.0.0.1:56136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4692,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:44.93307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:44.467668Z","time spent":"465.330893ms","remote":"127.0.0.1:58516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1696,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:44.933213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:44.275298Z","time spent":"657.802406ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:44.933595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.561164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1711"} {"level":"info","ts":"2026-08-22T18:45:44.933679Z","caller":"traceutil/trace.go:171","msg":"trace[828430297] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:729; }","duration":"306.261025ms","start":"2026-08-22T18:45:44.627397Z","end":"2026-08-22T18:45:44.933658Z","steps":["trace[828430297] 'agreement among raft nodes before linearized reading' (duration: 305.555143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:44.933762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:44.627371Z","time spent":"306.375747ms","remote":"127.0.0.1:58516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1734,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"info","ts":"2026-08-22T18:45:45.204964Z","caller":"traceutil/trace.go:171","msg":"trace[1120256483] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"265.752446ms","start":"2026-08-22T18:45:44.939181Z","end":"2026-08-22T18:45:45.204933Z","steps":["trace[1120256483] 'process raft request' (duration: 236.0331ms)","trace[1120256483] 'compare' (duration: 29.524363ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:45.205308Z","caller":"traceutil/trace.go:171","msg":"trace[1292978133] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"265.801016ms","start":"2026-08-22T18:45:44.939468Z","end":"2026-08-22T18:45:45.205269Z","steps":["trace[1292978133] 'process raft request' (duration: 265.42926ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:45.205435Z","caller":"traceutil/trace.go:171","msg":"trace[2051860591] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:758; }","duration":"261.421276ms","start":"2026-08-22T18:45:44.943997Z","end":"2026-08-22T18:45:45.205418Z","steps":["trace[2051860591] 'read index received' (duration: 37.967561ms)","trace[2051860591] 'applied index is now lower than readState.Index' (duration: 223.452605ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:45.205559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.531328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2074"} {"level":"info","ts":"2026-08-22T18:45:45.205602Z","caller":"traceutil/trace.go:171","msg":"trace[154866068] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:733; }","duration":"261.62461ms","start":"2026-08-22T18:45:44.943966Z","end":"2026-08-22T18:45:45.205591Z","steps":["trace[154866068] 'agreement among raft nodes before linearized reading' (duration: 261.524449ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:45.205675Z","caller":"traceutil/trace.go:171","msg":"trace[277176082] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"264.977583ms","start":"2026-08-22T18:45:44.940561Z","end":"2026-08-22T18:45:45.205538Z","steps":["trace[277176082] 'process raft request' (duration: 264.554007ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:45.445232Z","caller":"traceutil/trace.go:171","msg":"trace[425347993] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"137.921325ms","start":"2026-08-22T18:45:45.307283Z","end":"2026-08-22T18:45:45.445204Z","steps":["trace[425347993] 'read index received' (duration: 88.060309ms)","trace[425347993] 'applied index is now lower than readState.Index' (duration: 49.859526ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:45.445261Z","caller":"traceutil/trace.go:171","msg":"trace[181127800] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"142.193072ms","start":"2026-08-22T18:45:45.303025Z","end":"2026-08-22T18:45:45.445218Z","steps":["trace[181127800] 'process raft request' (duration: 92.314786ms)","trace[181127800] 'compare' (duration: 49.723574ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:45.445465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.143279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T18:45:45.445576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.216639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:45.445854Z","caller":"traceutil/trace.go:171","msg":"trace[1588190913] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:735; }","duration":"138.562846ms","start":"2026-08-22T18:45:45.307269Z","end":"2026-08-22T18:45:45.445832Z","steps":["trace[1588190913] 'agreement among raft nodes before linearized reading' (duration: 138.217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:45.445608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.62294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:45.446037Z","caller":"traceutil/trace.go:171","msg":"trace[880440570] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:735; }","duration":"138.086089ms","start":"2026-08-22T18:45:45.307916Z","end":"2026-08-22T18:45:45.446002Z","steps":["trace[880440570] 'agreement among raft nodes before linearized reading' (duration: 137.634091ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:45.445698Z","caller":"traceutil/trace.go:171","msg":"trace[2043738686] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:735; }","duration":"138.442585ms","start":"2026-08-22T18:45:45.307239Z","end":"2026-08-22T18:45:45.445682Z","steps":["trace[2043738686] 'agreement among raft nodes before linearized reading' (duration: 138.136859ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:46.356508Z","caller":"traceutil/trace.go:171","msg":"trace[195338374] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"105.61412ms","start":"2026-08-22T18:45:46.250871Z","end":"2026-08-22T18:45:46.356485Z","steps":["trace[195338374] 'read index received' (duration: 105.396967ms)","trace[195338374] 'applied index is now lower than readState.Index' (duration: 216.403µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:45:46.356663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.770172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T18:45:46.356711Z","caller":"traceutil/trace.go:171","msg":"trace[1707472836] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:736; }","duration":"105.863514ms","start":"2026-08-22T18:45:46.250829Z","end":"2026-08-22T18:45:46.356692Z","steps":["trace[1707472836] 'agreement among raft nodes before linearized reading' (duration: 105.758263ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:46.356912Z","caller":"traceutil/trace.go:171","msg":"trace[405610754] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"258.691045ms","start":"2026-08-22T18:45:46.098178Z","end":"2026-08-22T18:45:46.356869Z","steps":["trace[405610754] 'process raft request' (duration: 258.162417ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:46.869502Z","caller":"traceutil/trace.go:171","msg":"trace[390593224] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"182.948981ms","start":"2026-08-22T18:45:46.686482Z","end":"2026-08-22T18:45:46.869431Z","steps":["trace[390593224] 'process raft request' (duration: 182.657456ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:47.164756Z","caller":"traceutil/trace.go:171","msg":"trace[265044919] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"279.768006ms","start":"2026-08-22T18:45:46.88496Z","end":"2026-08-22T18:45:47.164728Z","steps":["trace[265044919] 'process raft request' (duration: 279.598874ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:47.198318Z","caller":"traceutil/trace.go:171","msg":"trace[69594473] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:769; }","duration":"259.779525ms","start":"2026-08-22T18:45:46.938509Z","end":"2026-08-22T18:45:47.198288Z","steps":["trace[69594473] 'read index received' (duration: 226.121543ms)","trace[69594473] 'applied index is now lower than readState.Index' (duration: 33.656582ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:47.198478Z","caller":"traceutil/trace.go:171","msg":"trace[1097042458] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"265.11063ms","start":"2026-08-22T18:45:46.933323Z","end":"2026-08-22T18:45:47.198434Z","steps":["trace[1097042458] 'process raft request' (duration: 264.827355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:47.198618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.07648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-22T18:45:47.198664Z","caller":"traceutil/trace.go:171","msg":"trace[195348863] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:741; }","duration":"260.198712ms","start":"2026-08-22T18:45:46.938453Z","end":"2026-08-22T18:45:47.198652Z","steps":["trace[195348863] 'agreement among raft nodes before linearized reading' (duration: 259.977599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:47.213141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.917939ms","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-22T18:45:47.213263Z","caller":"traceutil/trace.go:171","msg":"trace[1135931487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"225.061231ms","start":"2026-08-22T18:45:46.98815Z","end":"2026-08-22T18:45:47.213212Z","steps":["trace[1135931487] 'agreement among raft nodes before linearized reading' (duration: 224.828598ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:49.357472Z","caller":"traceutil/trace.go:171","msg":"trace[1711784432] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"113.284314ms","start":"2026-08-22T18:45:49.243877Z","end":"2026-08-22T18:45:49.357162Z","steps":["trace[1711784432] 'process raft request' (duration: 94.210076ms)","trace[1711784432] 'compare' (duration: 18.872705ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:49.358289Z","caller":"traceutil/trace.go:171","msg":"trace[561909520] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"114.353541ms","start":"2026-08-22T18:45:49.243879Z","end":"2026-08-22T18:45:49.358233Z","steps":["trace[561909520] 'process raft request' (duration: 113.234784ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:49.358519Z","caller":"traceutil/trace.go:171","msg":"trace[953541209] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"114.394512ms","start":"2026-08-22T18:45:49.244087Z","end":"2026-08-22T18:45:49.358482Z","steps":["trace[953541209] 'process raft request' (duration: 114.039286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:53.862551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.386287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T18:45:53.862678Z","caller":"traceutil/trace.go:171","msg":"trace[435068211] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:788; }","duration":"210.608301ms","start":"2026-08-22T18:45:53.652043Z","end":"2026-08-22T18:45:53.862652Z","steps":["trace[435068211] 'count revisions from in-memory index tree' (duration: 210.252786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:55.145584Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143327774130461132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T18:45:55.646335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143327774130461132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T18:45:55.709774Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.089448839s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T18:45:55.710277Z","caller":"traceutil/trace.go:171","msg":"trace[1880354591] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"1.065643677s","start":"2026-08-22T18:45:54.644609Z","end":"2026-08-22T18:45:55.710253Z","steps":["trace[1880354591] 'read index received' (duration: 1.065388343s)","trace[1880354591] 'applied index is now lower than readState.Index' (duration: 254.374µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:45:55.710362Z","caller":"traceutil/trace.go:171","msg":"trace[671666810] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"1.09015729s","start":"2026-08-22T18:45:54.620193Z","end":"2026-08-22T18:45:55.71035Z","steps":["trace[671666810] 'process raft request' (duration: 1.089901546s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:55.710489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:54.620153Z","time spent":"1.090236372s","remote":"127.0.0.1:56184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T18:45:55.710681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066056872s","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-22T18:45:55.710813Z","caller":"traceutil/trace.go:171","msg":"trace[934605230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"1.066239485s","start":"2026-08-22T18:45:54.644545Z","end":"2026-08-22T18:45:55.710785Z","steps":["trace[934605230] 'agreement among raft nodes before linearized reading' (duration: 1.065939551s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:55.710877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:54.644521Z","time spent":"1.066342907s","remote":"127.0.0.1:56210","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-22T18:45:55.711194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.501696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:55.711275Z","caller":"traceutil/trace.go:171","msg":"trace[464821375] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:789; }","duration":"537.639138ms","start":"2026-08-22T18:45:55.173615Z","end":"2026-08-22T18:45:55.711254Z","steps":["trace[464821375] 'agreement among raft nodes before linearized reading' (duration: 537.513286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:55.711326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:55.173585Z","time spent":"537.727299ms","remote":"127.0.0.1:56252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-08-22T18:45:55.711382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.397336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T18:45:55.711469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.325199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T18:45:55.711513Z","caller":"traceutil/trace.go:171","msg":"trace[859750881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"236.412711ms","start":"2026-08-22T18:45:55.475086Z","end":"2026-08-22T18:45:55.711499Z","steps":["trace[859750881] 'agreement among raft nodes before linearized reading' (duration: 236.35444ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:55.711495Z","caller":"traceutil/trace.go:171","msg":"trace[1394160429] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:789; }","duration":"310.578439ms","start":"2026-08-22T18:45:55.400895Z","end":"2026-08-22T18:45:55.711473Z","steps":["trace[1394160429] 'agreement among raft nodes before linearized reading' (duration: 310.384406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:55.711594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:55.400861Z","time spent":"310.71405ms","remote":"127.0.0.1:56342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-08-22T18:45:55.711407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.435032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-22T18:45:55.711862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.845758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-22T18:45:55.711909Z","caller":"traceutil/trace.go:171","msg":"trace[94836683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"238.9492ms","start":"2026-08-22T18:45:55.472946Z","end":"2026-08-22T18:45:55.711895Z","steps":["trace[94836683] 'agreement among raft nodes before linearized reading' (duration: 238.756207ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:45:55.711913Z","caller":"traceutil/trace.go:171","msg":"trace[1626731197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"238.961089ms","start":"2026-08-22T18:45:55.47291Z","end":"2026-08-22T18:45:55.711871Z","steps":["trace[1626731197] 'agreement among raft nodes before linearized reading' (duration: 238.363591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:55.711676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.671569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-22T18:45:55.711999Z","caller":"traceutil/trace.go:171","msg":"trace[97208596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:789; }","duration":"442.085616ms","start":"2026-08-22T18:45:55.269896Z","end":"2026-08-22T18:45:55.711982Z","steps":["trace[97208596] 'agreement among raft nodes before linearized reading' (duration: 441.65288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:45:55.712002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.117622ms","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-22T18:45:55.71206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:45:55.269862Z","time spent":"442.179798ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T18:45:55.712082Z","caller":"traceutil/trace.go:171","msg":"trace[564528410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:789; }","duration":"239.259464ms","start":"2026-08-22T18:45:55.472804Z","end":"2026-08-22T18:45:55.712064Z","steps":["trace[564528410] 'agreement among raft nodes before linearized reading' (duration: 238.98865ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:46:12.953752Z","caller":"traceutil/trace.go:171","msg":"trace[780647154] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"118.455479ms","start":"2026-08-22T18:46:12.835246Z","end":"2026-08-22T18:46:12.953701Z","steps":["trace[780647154] 'process raft request' (duration: 118.301307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:46:16.889897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.225446ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-22T18:46:21.15429Z","caller":"traceutil/trace.go:171","msg":"trace[1409526440] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"170.083067ms","start":"2026-08-22T18:46:20.984176Z","end":"2026-08-22T18:46:21.154259Z","steps":["trace[1409526440] 'process raft request' (duration: 169.933165ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:46:22.129273Z","caller":"traceutil/trace.go:171","msg":"trace[1035250951] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1040; }","duration":"106.352962ms","start":"2026-08-22T18:46:22.022893Z","end":"2026-08-22T18:46:22.129246Z","steps":["trace[1035250951] 'read index received' (duration: 59.341185ms)","trace[1035250951] 'applied index is now lower than readState.Index' (duration: 47.010627ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:46:22.129376Z","caller":"traceutil/trace.go:171","msg":"trace[753446822] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"112.622548ms","start":"2026-08-22T18:46:22.016741Z","end":"2026-08-22T18:46:22.129363Z","steps":["trace[753446822] 'process raft request' (duration: 65.484049ms)","trace[753446822] 'compare' (duration: 46.776174ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:46:22.129422Z","caller":"traceutil/trace.go:171","msg":"trace[988369742] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"112.153521ms","start":"2026-08-22T18:46:22.017257Z","end":"2026-08-22T18:46:22.129411Z","steps":["trace[988369742] 'process raft request' (duration: 111.903047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:46:22.129674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.740928ms","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-22T18:46:22.129713Z","caller":"traceutil/trace.go:171","msg":"trace[1749448182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"106.86096ms","start":"2026-08-22T18:46:22.022843Z","end":"2026-08-22T18:46:22.129704Z","steps":["trace[1749448182] 'agreement among raft nodes before linearized reading' (duration: 106.686878ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:46:22.271Z","caller":"traceutil/trace.go:171","msg":"trace[1021825268] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"137.687018ms","start":"2026-08-22T18:46:22.133284Z","end":"2026-08-22T18:46:22.270971Z","steps":["trace[1021825268] 'process raft request' (duration: 135.888394ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:46:22.271977Z","caller":"traceutil/trace.go:171","msg":"trace[2045124005] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"136.46123ms","start":"2026-08-22T18:46:22.135499Z","end":"2026-08-22T18:46:22.27196Z","steps":["trace[2045124005] 'process raft request' (duration: 136.42151ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:46:22.272266Z","caller":"traceutil/trace.go:171","msg":"trace[26638424] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"138.348967ms","start":"2026-08-22T18:46:22.133906Z","end":"2026-08-22T18:46:22.272254Z","steps":["trace[26638424] 'process raft request' (duration: 137.920141ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:46:28.144185Z","caller":"traceutil/trace.go:171","msg":"trace[875791891] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"101.319778ms","start":"2026-08-22T18:46:28.042833Z","end":"2026-08-22T18:46:28.144153Z","steps":["trace[875791891] 'process raft request' (duration: 101.107175ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:46:29.141053Z","caller":"traceutil/trace.go:171","msg":"trace[1878067588] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"123.831931ms","start":"2026-08-22T18:46:29.017191Z","end":"2026-08-22T18:46:29.141023Z","steps":["trace[1878067588] 'process raft request' (duration: 123.664639ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:47:55.294658Z","caller":"traceutil/trace.go:171","msg":"trace[1209121842] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"457.982728ms","start":"2026-08-22T18:47:54.836637Z","end":"2026-08-22T18:47:55.29462Z","steps":["trace[1209121842] 'process raft request' (duration: 457.755025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:47:55.29494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:47:54.836626Z","time spent":"458.15727ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:47:55.361673Z","caller":"traceutil/trace.go:171","msg":"trace[216866474] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1410; }","duration":"484.09498ms","start":"2026-08-22T18:47:54.877555Z","end":"2026-08-22T18:47:55.36165Z","steps":["trace[216866474] 'read index received' (duration: 416.845416ms)","trace[216866474] 'applied index is now lower than readState.Index' (duration: 67.248763ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:47:55.361747Z","caller":"traceutil/trace.go:171","msg":"trace[84471036] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"524.013357ms","start":"2026-08-22T18:47:54.837696Z","end":"2026-08-22T18:47:55.361709Z","steps":["trace[84471036] 'process raft request' (duration: 523.841385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:47:55.361839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.270901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-22T18:47:55.361861Z","caller":"traceutil/trace.go:171","msg":"trace[10599872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1353; }","duration":"484.356603ms","start":"2026-08-22T18:47:54.877499Z","end":"2026-08-22T18:47:55.361855Z","steps":["trace[10599872] 'agreement among raft nodes before linearized reading' (duration: 484.244572ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:47:55.361887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:47:54.877476Z","time spent":"484.405243ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T18:47:55.361996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T18:47:54.837676Z","time spent":"524.158659ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T18:47:55.562829Z","caller":"traceutil/trace.go:171","msg":"trace[1917713427] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"195.494512ms","start":"2026-08-22T18:47:55.367277Z","end":"2026-08-22T18:47:55.562772Z","steps":["trace[1917713427] 'process raft request' (duration: 96.38915ms)","trace[1917713427] 'compare' (duration: 98.748007ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:47:56.902179Z","caller":"traceutil/trace.go:171","msg":"trace[1742198799] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"246.086097ms","start":"2026-08-22T18:47:56.656069Z","end":"2026-08-22T18:47:56.902156Z","steps":["trace[1742198799] 'process raft request' (duration: 245.952785ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:47:56.902654Z","caller":"traceutil/trace.go:171","msg":"trace[2107577743] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1415; }","duration":"213.642863ms","start":"2026-08-22T18:47:56.688996Z","end":"2026-08-22T18:47:56.902639Z","steps":["trace[2107577743] 'read index received' (duration: 213.639183ms)","trace[2107577743] 'applied index is now lower than readState.Index' (duration: 2.9µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T18:47:56.902793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.784654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-22T18:47:56.902831Z","caller":"traceutil/trace.go:171","msg":"trace[1139055410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1356; }","duration":"213.870466ms","start":"2026-08-22T18:47:56.688951Z","end":"2026-08-22T18:47:56.902822Z","steps":["trace[1139055410] 'agreement among raft nodes before linearized reading' (duration: 213.740235ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:47:57.810068Z","caller":"traceutil/trace.go:171","msg":"trace[670461703] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"162.555519ms","start":"2026-08-22T18:47:57.647489Z","end":"2026-08-22T18:47:57.810045Z","steps":["trace[670461703] 'process raft request' (duration: 162.079374ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T18:48:31.702073Z","caller":"traceutil/trace.go:171","msg":"trace[18637555] linearizableReadLoop","detail":"{readStateIndex:1544; appliedIndex:1543; }","duration":"103.089418ms","start":"2026-08-22T18:48:31.598963Z","end":"2026-08-22T18:48:31.702052Z","steps":["trace[18637555] 'read index received' (duration: 102.835851ms)","trace[18637555] 'applied index is now lower than readState.Index' (duration: 251.947µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T18:48:31.702149Z","caller":"traceutil/trace.go:171","msg":"trace[460740027] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"109.343677ms","start":"2026-08-22T18:48:31.592765Z","end":"2026-08-22T18:48:31.702109Z","steps":["trace[460740027] 'process raft request' (duration: 109.131341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T18:48:31.702304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.328226ms","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-22T18:48:31.702564Z","caller":"traceutil/trace.go:171","msg":"trace[129946139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1476; }","duration":"103.622134ms","start":"2026-08-22T18:48:31.598928Z","end":"2026-08-22T18:48:31.70255Z","steps":["trace[129946139] 'agreement among raft nodes before linearized reading' (duration: 103.261764ms)"],"step_count":1}