{"level":"warn","ts":"2026-05-05T10:17:32.764257Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T10:17:32.764415Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T10:17:32.764483Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T10:17:32.76449Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-05T10:17:32.764507Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T10:17:32.765109Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-05-05T10:17:32.765176Z","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.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T10:17:32.769839Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.456666ms"} {"level":"info","ts":"2026-05-05T10:17:32.778387Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-05-05T10:17:32.778482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T10:17:32.77852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-05-05T10:17:32.778546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T10:17:32.778583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-05-05T10:17:32.778659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-05-05T10:17:32.789317Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T10:17:32.790613Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T10:17:32.79169Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T10:17:32.793696Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T10:17:32.793867Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T10:17:32.793963Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T10:17:32.793984Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T10:17:32.794179Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T10:17:32.794786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-05-05T10:17:32.794862Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-05T10:17:32.799122Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T10:17:32.79928Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-05T10:17:32.799319Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-05T10:17:32.79976Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T10:17:32.799802Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T10:17:33.179343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T10:17:33.179413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T10:17:33.179442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-05-05T10:17:33.179461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-05-05T10:17:33.179476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-05T10:17:33.179495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-05-05T10:17:33.179511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-05T10:17:33.180528Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T10:17:33.180556Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:17:33.180528Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T10:17:33.180589Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T10:17:33.180859Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T10:17:33.180878Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T10:17:33.181382Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:17:33.18152Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:17:33.181554Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:17:33.182801Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T10:17:33.184729Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"warn","ts":"2026-05-05T10:17:35.978069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.810267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:35.978183Z","caller":"traceutil/trace.go:171","msg":"trace[726190808] range","detail":"{range_begin:/registry/clusterrolebindings/system:node; range_end:; response_count:0; response_revision:145; }","duration":"123.992831ms","start":"2026-05-05T10:17:35.854165Z","end":"2026-05-05T10:17:35.978158Z","steps":["trace[726190808] 'range keys from in-memory index tree' (duration: 123.712545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:42.06252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.728365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-05T10:17:42.062599Z","caller":"traceutil/trace.go:171","msg":"trace[1696549560] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:288; }","duration":"241.857018ms","start":"2026-05-05T10:17:41.820725Z","end":"2026-05-05T10:17:42.062582Z","steps":["trace[1696549560] 'range keys from in-memory index tree' (duration: 241.639073ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:42.480053Z","caller":"traceutil/trace.go:171","msg":"trace[1034623702] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"413.481306ms","start":"2026-05-05T10:17:42.066545Z","end":"2026-05-05T10:17:42.480026Z","steps":["trace[1034623702] 'process raft request' (duration: 413.282201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:42.480605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:42.066498Z","time spent":"413.64943ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":184,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T10:17:43.541365Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.061408856s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T10:17:43.541849Z","caller":"traceutil/trace.go:171","msg":"trace[1629578648] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:303; }","duration":"1.150235476s","start":"2026-05-05T10:17:42.391587Z","end":"2026-05-05T10:17:43.541823Z","steps":["trace[1629578648] 'read index received' (duration: 88.572004ms)","trace[1629578648] 'applied index is now lower than readState.Index' (duration: 1.061662332s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:17:43.541987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150382209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T10:17:43.541975Z","caller":"traceutil/trace.go:171","msg":"trace[210710649] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"1.475032026s","start":"2026-05-05T10:17:42.066909Z","end":"2026-05-05T10:17:43.541941Z","steps":["trace[210710649] 'process raft request' (duration: 1.474743999s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:43.542075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057231097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:43.542105Z","caller":"traceutil/trace.go:171","msg":"trace[36547876] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:290; }","duration":"1.057286409s","start":"2026-05-05T10:17:42.484811Z","end":"2026-05-05T10:17:43.542098Z","steps":["trace[36547876] 'agreement among raft nodes before linearized reading' (duration: 1.057241228s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:43.542138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:42.484796Z","time spent":"1.057333579s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-05-05T10:17:43.542241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.426391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T10:17:43.542347Z","caller":"traceutil/trace.go:171","msg":"trace[240119445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:290; }","duration":"541.580265ms","start":"2026-05-05T10:17:43.000746Z","end":"2026-05-05T10:17:43.542327Z","steps":["trace[240119445] 'agreement among raft nodes before linearized reading' (duration: 541.412291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:43.542404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:43.000725Z","time spent":"541.667077ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T10:17:43.542473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.555334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T10:17:43.542525Z","caller":"traceutil/trace.go:171","msg":"trace[244764692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:290; }","duration":"744.601496ms","start":"2026-05-05T10:17:42.797909Z","end":"2026-05-05T10:17:43.542511Z","steps":["trace[244764692] 'agreement among raft nodes before linearized reading' (duration: 744.528874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:43.542555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:42.797841Z","time spent":"744.707297ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T10:17:43.542401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:42.066896Z","time spent":"1.475174089s","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:17:43.542026Z","caller":"traceutil/trace.go:171","msg":"trace[231505014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:290; }","duration":"1.150476192s","start":"2026-05-05T10:17:42.391538Z","end":"2026-05-05T10:17:43.542014Z","steps":["trace[231505014] 'agreement among raft nodes before linearized reading' (duration: 1.15038959s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:43.542703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:42.39152Z","time spent":"1.151170819s","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T10:17:43.669804Z","caller":"traceutil/trace.go:171","msg":"trace[33322704] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"122.476566ms","start":"2026-05-05T10:17:43.547285Z","end":"2026-05-05T10:17:43.669761Z","steps":["trace[33322704] 'process raft request' (duration: 59.437325ms)","trace[33322704] 'compare' (duration: 62.772974ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:43.670063Z","caller":"traceutil/trace.go:171","msg":"trace[57418080] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"122.749622ms","start":"2026-05-05T10:17:43.547285Z","end":"2026-05-05T10:17:43.670035Z","steps":["trace[57418080] 'process raft request' (duration: 122.422174ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:43.670194Z","caller":"traceutil/trace.go:171","msg":"trace[501447708] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"122.848544ms","start":"2026-05-05T10:17:43.547325Z","end":"2026-05-05T10:17:43.670174Z","steps":["trace[501447708] 'process raft request' (duration: 122.632389ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:43.670202Z","caller":"traceutil/trace.go:171","msg":"trace[758982535] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"120.230233ms","start":"2026-05-05T10:17:43.549946Z","end":"2026-05-05T10:17:43.670176Z","steps":["trace[758982535] 'process raft request' (duration: 120.07406ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:43.836696Z","caller":"traceutil/trace.go:171","msg":"trace[2146776327] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"158.725313ms","start":"2026-05-05T10:17:43.67788Z","end":"2026-05-05T10:17:43.836605Z","steps":["trace[2146776327] 'process raft request' (duration: 110.684427ms)","trace[2146776327] 'compare' (duration: 47.903583ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:51.305815Z","caller":"traceutil/trace.go:171","msg":"trace[140517506] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"103.933007ms","start":"2026-05-05T10:17:51.201856Z","end":"2026-05-05T10:17:51.305789Z","steps":["trace[140517506] 'process raft request' (duration: 103.711452ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:05.781039Z","caller":"traceutil/trace.go:171","msg":"trace[1196583623] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"105.471614ms","start":"2026-05-05T10:18:05.675549Z","end":"2026-05-05T10:18:05.78102Z","steps":["trace[1196583623] 'process raft request' (duration: 105.376582ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:05.78104Z","caller":"traceutil/trace.go:171","msg":"trace[875865710] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"106.013997ms","start":"2026-05-05T10:18:05.674998Z","end":"2026-05-05T10:18:05.781012Z","steps":["trace[875865710] 'process raft request' (duration: 105.842883ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:06.789096Z","caller":"traceutil/trace.go:171","msg":"trace[784205181] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"141.136827ms","start":"2026-05-05T10:18:06.647938Z","end":"2026-05-05T10:18:06.789075Z","steps":["trace[784205181] 'process raft request' (duration: 134.439149ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:09.959633Z","caller":"traceutil/trace.go:171","msg":"trace[922121077] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"104.637102ms","start":"2026-05-05T10:18:09.854971Z","end":"2026-05-05T10:18:09.959608Z","steps":["trace[922121077] 'process raft request' (duration: 99.790515ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:11.719009Z","caller":"traceutil/trace.go:171","msg":"trace[40313000] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"247.217369ms","start":"2026-05-05T10:18:11.471765Z","end":"2026-05-05T10:18:11.718983Z","steps":["trace[40313000] 'read index received' (duration: 246.509122ms)","trace[40313000] 'applied index is now lower than readState.Index' (duration: 707.317µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:18:11.719151Z","caller":"traceutil/trace.go:171","msg":"trace[222255815] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"331.381297ms","start":"2026-05-05T10:18:11.387708Z","end":"2026-05-05T10:18:11.71909Z","steps":["trace[222255815] 'process raft request' (duration: 330.740702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:11.719206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.425773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T10:18:11.719385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:11.387676Z","time spent":"331.593033ms","remote":"127.0.0.1:37022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:18:11.719438Z","caller":"traceutil/trace.go:171","msg":"trace[1182435706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"247.672039ms","start":"2026-05-05T10:18:11.471718Z","end":"2026-05-05T10:18:11.71939Z","steps":["trace[1182435706] 'agreement among raft nodes before linearized reading' (duration: 247.436774ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:11.874974Z","caller":"traceutil/trace.go:171","msg":"trace[1386574950] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"101.667571ms","start":"2026-05-05T10:18:11.773277Z","end":"2026-05-05T10:18:11.874945Z","steps":["trace[1386574950] 'process raft request' (duration: 101.503377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:12.616913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.883149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:18:12.617123Z","caller":"traceutil/trace.go:171","msg":"trace[1081115969] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:605; }","duration":"729.3351ms","start":"2026-05-05T10:18:11.887771Z","end":"2026-05-05T10:18:12.617106Z","steps":["trace[1081115969] 'read index received' (duration: 72.804715ms)","trace[1081115969] 'applied index is now lower than readState.Index' (duration: 656.529595ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:18:12.617462Z","caller":"traceutil/trace.go:171","msg":"trace[92920826] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"737.763722ms","start":"2026-05-05T10:18:11.87966Z","end":"2026-05-05T10:18:12.617423Z","steps":["trace[92920826] 'process raft request' (duration: 737.360653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:12.617709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:11.879639Z","time spent":"737.925487ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:18:12.617797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.018346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:12.617842Z","caller":"traceutil/trace.go:171","msg":"trace[300853744] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:580; }","duration":"730.089027ms","start":"2026-05-05T10:18:11.887741Z","end":"2026-05-05T10:18:12.61783Z","steps":["trace[300853744] 'agreement among raft nodes before linearized reading' (duration: 729.455412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:12.617881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:11.887731Z","time spent":"730.140779ms","remote":"127.0.0.1:37466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-05-05T10:18:12.618005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.946208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:12.618155Z","caller":"traceutil/trace.go:171","msg":"trace[136300583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"146.134513ms","start":"2026-05-05T10:18:12.471999Z","end":"2026-05-05T10:18:12.618133Z","steps":["trace[136300583] 'agreement among raft nodes before linearized reading' (duration: 145.525708ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:12.618303Z","caller":"traceutil/trace.go:171","msg":"trace[951134196] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"737.986708ms","start":"2026-05-05T10:18:11.880302Z","end":"2026-05-05T10:18:12.618289Z","steps":["trace[951134196] 'process raft request' (duration: 736.770819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:12.618396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:11.880292Z","time spent":"738.06112ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:18:12.61865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.800551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T10:18:12.618801Z","caller":"traceutil/trace.go:171","msg":"trace[219050643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"729.994296ms","start":"2026-05-05T10:18:11.888776Z","end":"2026-05-05T10:18:12.61877Z","steps":["trace[219050643] 'agreement among raft nodes before linearized reading' (duration: 728.462809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:12.618839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.687804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ftfd6\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-05T10:18:12.618977Z","caller":"traceutil/trace.go:171","msg":"trace[801232054] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ftfd6; range_end:; response_count:1; response_revision:580; }","duration":"711.855238ms","start":"2026-05-05T10:18:11.907083Z","end":"2026-05-05T10:18:12.618938Z","steps":["trace[801232054] 'agreement among raft nodes before linearized reading' (duration: 710.591548ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:12.619028Z","caller":"traceutil/trace.go:171","msg":"trace[2050213498] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"738.245325ms","start":"2026-05-05T10:18:11.879223Z","end":"2026-05-05T10:18:12.617468Z","steps":["trace[2050213498] 'process raft request' (duration: 81.361141ms)","trace[2050213498] 'compare' (duration: 655.748356ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:18:12.619062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:11.907073Z","time spent":"711.974011ms","remote":"127.0.0.1:37128","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-ftfd6\" "} {"level":"warn","ts":"2026-05-05T10:18:12.619129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:11.879176Z","time spent":"739.907574ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:18:12.618877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:11.88876Z","time spent":"730.101948ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T10:18:12.801496Z","caller":"traceutil/trace.go:171","msg":"trace[1625962123] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"176.273628ms","start":"2026-05-05T10:18:12.625193Z","end":"2026-05-05T10:18:12.801467Z","steps":["trace[1625962123] 'process raft request' (duration: 105.701707ms)","trace[1625962123] 'compare' (duration: 70.448088ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:18:13.540065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.384769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:18:13.540317Z","caller":"traceutil/trace.go:171","msg":"trace[324593478] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"902.427031ms","start":"2026-05-05T10:18:12.637861Z","end":"2026-05-05T10:18:13.540288Z","steps":["trace[324593478] 'process raft request' (duration: 762.684653ms)","trace[324593478] 'compare' (duration: 139.187155ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:18:13.540437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:12.637829Z","time spent":"902.572985ms","remote":"127.0.0.1:37128","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-05-05T10:18:13.974446Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102859682639576,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T10:18:14.105636Z","caller":"traceutil/trace.go:171","msg":"trace[1925518658] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"631.681566ms","start":"2026-05-05T10:18:13.473903Z","end":"2026-05-05T10:18:14.105584Z","steps":["trace[1925518658] 'read index received' (duration: 630.713682ms)","trace[1925518658] 'applied index is now lower than readState.Index' (duration: 966.944µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:18:14.10578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.865831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:14.105802Z","caller":"traceutil/trace.go:171","msg":"trace[344923175] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.091488409s","start":"2026-05-05T10:18:13.014273Z","end":"2026-05-05T10:18:14.105762Z","steps":["trace[344923175] 'process raft request' (duration: 1.090646149s)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:14.105824Z","caller":"traceutil/trace.go:171","msg":"trace[1672556724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"631.958082ms","start":"2026-05-05T10:18:13.47385Z","end":"2026-05-05T10:18:14.105808Z","steps":["trace[1672556724] 'agreement among raft nodes before linearized reading' (duration: 631.85255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:14.105871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:13.473832Z","time spent":"632.028694ms","remote":"127.0.0.1:36898","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-05-05T10:18:14.105923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:13.014249Z","time spent":"1.091607532s","remote":"127.0.0.1:37022","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-05-05T10:18:14.802421Z","caller":"traceutil/trace.go:171","msg":"trace[1723564423] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"220.113375ms","start":"2026-05-05T10:18:14.582285Z","end":"2026-05-05T10:18:14.802399Z","steps":["trace[1723564423] 'read index received' (duration: 218.358533ms)","trace[1723564423] 'applied index is now lower than readState.Index' (duration: 1.753652ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:18:14.802494Z","caller":"traceutil/trace.go:171","msg":"trace[418927563] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"222.088713ms","start":"2026-05-05T10:18:14.580374Z","end":"2026-05-05T10:18:14.802462Z","steps":["trace[418927563] 'process raft request' (duration: 220.591077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:14.802597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.284369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:14.802656Z","caller":"traceutil/trace.go:171","msg":"trace[456492006] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:585; }","duration":"220.384602ms","start":"2026-05-05T10:18:14.582258Z","end":"2026-05-05T10:18:14.802643Z","steps":["trace[456492006] 'agreement among raft nodes before linearized reading' (duration: 220.27694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:14.802608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.247019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:14.802848Z","caller":"traceutil/trace.go:171","msg":"trace[1841152719] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:585; }","duration":"220.493314ms","start":"2026-05-05T10:18:14.582322Z","end":"2026-05-05T10:18:14.802815Z","steps":["trace[1841152719] 'agreement among raft nodes before linearized reading' (duration: 220.243718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:14.80306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.907279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:14.803108Z","caller":"traceutil/trace.go:171","msg":"trace[1922416076] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"204.96609ms","start":"2026-05-05T10:18:14.59813Z","end":"2026-05-05T10:18:14.803096Z","steps":["trace[1922416076] 'agreement among raft nodes before linearized reading' (duration: 204.905949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:14.803233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.919517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T10:18:14.803262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.18092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:14.803293Z","caller":"traceutil/trace.go:171","msg":"trace[94828810] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"206.218341ms","start":"2026-05-05T10:18:14.597065Z","end":"2026-05-05T10:18:14.803283Z","steps":["trace[94828810] 'agreement among raft nodes before linearized reading' (duration: 206.17136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:14.803398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.547598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:14.803435Z","caller":"traceutil/trace.go:171","msg":"trace[1964530457] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"206.593959ms","start":"2026-05-05T10:18:14.596833Z","end":"2026-05-05T10:18:14.803427Z","steps":["trace[1964530457] 'agreement among raft nodes before linearized reading' (duration: 206.545808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:14.803548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.083829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:14.803298Z","caller":"traceutil/trace.go:171","msg":"trace[218002197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"180.008999ms","start":"2026-05-05T10:18:14.623273Z","end":"2026-05-05T10:18:14.803282Z","steps":["trace[218002197] 'agreement among raft nodes before linearized reading' (duration: 179.713882ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:14.80358Z","caller":"traceutil/trace.go:171","msg":"trace[454869933] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:585; }","duration":"221.1309ms","start":"2026-05-05T10:18:14.582441Z","end":"2026-05-05T10:18:14.803572Z","steps":["trace[454869933] 'agreement among raft nodes before linearized reading' (duration: 221.086299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:14.80389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.441279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T10:18:14.803931Z","caller":"traceutil/trace.go:171","msg":"trace[1151617384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"180.494941ms","start":"2026-05-05T10:18:14.623423Z","end":"2026-05-05T10:18:14.803918Z","steps":["trace[1151617384] 'agreement among raft nodes before linearized reading' (duration: 180.400599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:14.804132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.917826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T10:18:14.80422Z","caller":"traceutil/trace.go:171","msg":"trace[602643018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"180.05436ms","start":"2026-05-05T10:18:14.624154Z","end":"2026-05-05T10:18:14.804209Z","steps":["trace[602643018] 'agreement among raft nodes before linearized reading' (duration: 179.744733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:14.995119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.919825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:18:14.99569Z","caller":"traceutil/trace.go:171","msg":"trace[1109049557] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"185.505992ms","start":"2026-05-05T10:18:14.810159Z","end":"2026-05-05T10:18:14.995665Z","steps":["trace[1109049557] 'process raft request' (duration: 185.315697ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:14.995825Z","caller":"traceutil/trace.go:171","msg":"trace[1667051510] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"184.802235ms","start":"2026-05-05T10:18:14.810988Z","end":"2026-05-05T10:18:14.99579Z","steps":["trace[1667051510] 'process raft request' (duration: 184.553369ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:14.995958Z","caller":"traceutil/trace.go:171","msg":"trace[102006695] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"178.226196ms","start":"2026-05-05T10:18:14.817719Z","end":"2026-05-05T10:18:14.995945Z","steps":["trace[102006695] 'process raft request' (duration: 177.855167ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:14.996478Z","caller":"traceutil/trace.go:171","msg":"trace[693048127] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"134.119743ms","start":"2026-05-05T10:18:14.862344Z","end":"2026-05-05T10:18:14.996464Z","steps":["trace[693048127] 'process raft request' (duration: 133.258562ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:14.997493Z","caller":"traceutil/trace.go:171","msg":"trace[1253995712] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"188.092554ms","start":"2026-05-05T10:18:14.807647Z","end":"2026-05-05T10:18:14.995739Z","steps":["trace[1253995712] 'process raft request' (duration: 27.40291ms)","trace[1253995712] 'compare' (duration: 159.70379ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:18:20.215853Z","caller":"traceutil/trace.go:171","msg":"trace[783711357] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"222.961685ms","start":"2026-05-05T10:18:19.992792Z","end":"2026-05-05T10:18:20.215754Z","steps":["trace[783711357] 'process raft request' (duration: 159.465834ms)","trace[783711357] 'compare' (duration: 63.019699ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:18:30.909575Z","caller":"traceutil/trace.go:171","msg":"trace[660407598] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"277.675463ms","start":"2026-05-05T10:18:30.631876Z","end":"2026-05-05T10:18:30.909552Z","steps":["trace[660407598] 'process raft request' (duration: 277.524739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:05.585714Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.222861225s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T10:19:05.586152Z","caller":"traceutil/trace.go:171","msg":"trace[265199973] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"472.379036ms","start":"2026-05-05T10:19:05.113749Z","end":"2026-05-05T10:19:05.586128Z","steps":["trace[265199973] 'read index received' (duration: 472.11768ms)","trace[265199973] 'applied index is now lower than readState.Index' (duration: 260.316µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:19:05.586255Z","caller":"traceutil/trace.go:171","msg":"trace[363172139] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.238992234s","start":"2026-05-05T10:19:04.347243Z","end":"2026-05-05T10:19:05.586235Z","steps":["trace[363172139] 'process raft request' (duration: 1.238712337s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:05.586436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:04.347226Z","time spent":"1.239089237s","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:19:05.586573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.766331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T10:19:05.586571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.427002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T10:19:05.586617Z","caller":"traceutil/trace.go:171","msg":"trace[1302000625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"232.830312ms","start":"2026-05-05T10:19:05.353776Z","end":"2026-05-05T10:19:05.586606Z","steps":["trace[1302000625] 'agreement among raft nodes before linearized reading' (duration: 232.717789ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:05.586637Z","caller":"traceutil/trace.go:171","msg":"trace[393197783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"227.510424ms","start":"2026-05-05T10:19:05.359107Z","end":"2026-05-05T10:19:05.586617Z","steps":["trace[393197783] 'agreement among raft nodes before linearized reading' (duration: 227.375931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:05.586729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.819237ms","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-05-05T10:19:05.586792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.878739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:19:05.586841Z","caller":"traceutil/trace.go:171","msg":"trace[1146026971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"114.958371ms","start":"2026-05-05T10:19:05.47187Z","end":"2026-05-05T10:19:05.586829Z","steps":["trace[1146026971] 'agreement among raft nodes before linearized reading' (duration: 114.88951ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:05.586847Z","caller":"traceutil/trace.go:171","msg":"trace[107167688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"312.006831ms","start":"2026-05-05T10:19:05.274815Z","end":"2026-05-05T10:19:05.586822Z","steps":["trace[107167688] 'agreement among raft nodes before linearized reading' (duration: 311.653543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:05.586897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:05.274796Z","time spent":"312.093073ms","remote":"127.0.0.1:37220","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-05-05T10:19:05.587014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.240657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T10:19:05.587055Z","caller":"traceutil/trace.go:171","msg":"trace[265193026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:961; }","duration":"234.318459ms","start":"2026-05-05T10:19:05.352727Z","end":"2026-05-05T10:19:05.587046Z","steps":["trace[265193026] 'agreement among raft nodes before linearized reading' (duration: 234.229146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:05.586403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.643483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T10:19:05.587165Z","caller":"traceutil/trace.go:171","msg":"trace[2042402034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:961; }","duration":"473.451882ms","start":"2026-05-05T10:19:05.1137Z","end":"2026-05-05T10:19:05.587152Z","steps":["trace[2042402034] 'agreement among raft nodes before linearized reading' (duration: 472.55373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:05.587204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:05.113679Z","time spent":"473.513103ms","remote":"127.0.0.1:37220","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":"info","ts":"2026-05-05T10:19:10.00092Z","caller":"traceutil/trace.go:171","msg":"trace[1391971413] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"348.95127ms","start":"2026-05-05T10:19:09.651948Z","end":"2026-05-05T10:19:10.000899Z","steps":["trace[1391971413] 'process raft request' (duration: 315.432403ms)","trace[1391971413] 'compare' (duration: 33.182349ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:19:10.001042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:09.651938Z","time spent":"349.041052ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:19:10.001146Z","caller":"traceutil/trace.go:171","msg":"trace[798305499] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"348.740964ms","start":"2026-05-05T10:19:09.652381Z","end":"2026-05-05T10:19:10.001122Z","steps":["trace[798305499] 'process raft request' (duration: 348.443517ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:10.001275Z","caller":"traceutil/trace.go:171","msg":"trace[2101985272] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"348.381486ms","start":"2026-05-05T10:19:09.652885Z","end":"2026-05-05T10:19:10.001266Z","steps":["trace[2101985272] 'process raft request' (duration: 348.170421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:10.00129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:09.652361Z","time spent":"348.860458ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:19:10.001318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:09.652871Z","time spent":"348.427257ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:19:10.001394Z","caller":"traceutil/trace.go:171","msg":"trace[2062379188] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"347.987177ms","start":"2026-05-05T10:19:09.653361Z","end":"2026-05-05T10:19:10.001348Z","steps":["trace[2062379188] 'process raft request' (duration: 347.875424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:10.001534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:09.653351Z","time spent":"348.100779ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:19:28.252776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102859682640592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T10:19:28.753191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102859682640592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T10:19:29.253778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102859682640592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T10:19:29.490204Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.774796886s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T10:19:29.490713Z","caller":"traceutil/trace.go:171","msg":"trace[788113601] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"1.738204758s","start":"2026-05-05T10:19:27.752483Z","end":"2026-05-05T10:19:29.490688Z","steps":["trace[788113601] 'read index received' (duration: 1.73788743s)","trace[788113601] 'applied index is now lower than readState.Index' (duration: 315.757µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:19:29.490966Z","caller":"traceutil/trace.go:171","msg":"trace[1871400928] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"1.775655827s","start":"2026-05-05T10:19:27.715291Z","end":"2026-05-05T10:19:29.490946Z","steps":["trace[1871400928] 'process raft request' (duration: 1.775134394s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:29.491027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666699059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:19:29.491094Z","caller":"traceutil/trace.go:171","msg":"trace[1094058759] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1035; }","duration":"1.666848393s","start":"2026-05-05T10:19:27.824229Z","end":"2026-05-05T10:19:29.491078Z","steps":["trace[1094058759] 'agreement among raft nodes before linearized reading' (duration: 1.6667159s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:29.491139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:27.824207Z","time spent":"1.666917724s","remote":"127.0.0.1:55400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T10:19:29.491139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:27.715261Z","time spent":"1.775760608s","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:19:29.491378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385658386s","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-05-05T10:19:29.491448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.738954895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T10:19:29.491499Z","caller":"traceutil/trace.go:171","msg":"trace[2128301181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1035; }","duration":"1.739037766s","start":"2026-05-05T10:19:27.752446Z","end":"2026-05-05T10:19:29.491484Z","steps":["trace[2128301181] 'agreement among raft nodes before linearized reading' (duration: 1.738907353s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:29.491534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:27.752429Z","time spent":"1.739097598s","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T10:19:29.49143Z","caller":"traceutil/trace.go:171","msg":"trace[814953727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1035; }","duration":"1.385737568s","start":"2026-05-05T10:19:28.105678Z","end":"2026-05-05T10:19:29.491415Z","steps":["trace[814953727] 'agreement among raft nodes before linearized reading' (duration: 1.385572144s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:29.491497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.262493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T10:19:29.491593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:28.105662Z","time spent":"1.385912882s","remote":"127.0.0.1:37220","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-05-05T10:19:29.491605Z","caller":"traceutil/trace.go:171","msg":"trace[1003119994] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1035; }","duration":"338.444087ms","start":"2026-05-05T10:19:29.153141Z","end":"2026-05-05T10:19:29.491585Z","steps":["trace[1003119994] 'agreement among raft nodes before linearized reading' (duration: 338.204831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:29.491612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372247427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T10:19:29.491657Z","caller":"traceutil/trace.go:171","msg":"trace[1119298624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"1.372316369s","start":"2026-05-05T10:19:28.119327Z","end":"2026-05-05T10:19:29.491643Z","steps":["trace[1119298624] 'agreement among raft nodes before linearized reading' (duration: 1.372174176s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:29.491674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:29.153114Z","time spent":"338.54821ms","remote":"127.0.0.1:37226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-05T10:19:29.491693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:28.11931Z","time spent":"1.37237349s","remote":"127.0.0.1:37220","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-05-05T10:19:29.492012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372633977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T10:19:29.492065Z","caller":"traceutil/trace.go:171","msg":"trace[1402973840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"1.372690638s","start":"2026-05-05T10:19:28.119358Z","end":"2026-05-05T10:19:29.492049Z","steps":["trace[1402973840] 'agreement among raft nodes before linearized reading' (duration: 1.371850878s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:29.492106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:28.119292Z","time spent":"1.37280181s","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T10:19:29.492128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384512918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T10:19:29.49217Z","caller":"traceutil/trace.go:171","msg":"trace[1279516601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"1.38459384s","start":"2026-05-05T10:19:28.107562Z","end":"2026-05-05T10:19:29.492156Z","steps":["trace[1279516601] 'agreement among raft nodes before linearized reading' (duration: 1.383657368s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:29.492208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:28.107545Z","time spent":"1.384656522s","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T10:19:29.618905Z","caller":"traceutil/trace.go:171","msg":"trace[2000873670] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"122.0791ms","start":"2026-05-05T10:19:29.496795Z","end":"2026-05-05T10:19:29.618874Z","steps":["trace[2000873670] 'process raft request' (duration: 121.870395ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:29.619299Z","caller":"traceutil/trace.go:171","msg":"trace[726959632] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"122.019848ms","start":"2026-05-05T10:19:29.497243Z","end":"2026-05-05T10:19:29.619262Z","steps":["trace[726959632] 'process raft request' (duration: 121.571627ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:29.619372Z","caller":"traceutil/trace.go:171","msg":"trace[1622994138] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"122.007218ms","start":"2026-05-05T10:19:29.497341Z","end":"2026-05-05T10:19:29.619348Z","steps":["trace[1622994138] 'process raft request' (duration: 121.757252ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:29.619302Z","caller":"traceutil/trace.go:171","msg":"trace[1985180391] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"121.898735ms","start":"2026-05-05T10:19:29.497378Z","end":"2026-05-05T10:19:29.619277Z","steps":["trace[1985180391] 'process raft request' (duration: 121.848864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:31.971049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.618347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:19:31.971408Z","caller":"traceutil/trace.go:171","msg":"trace[1872554232] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"301.833758ms","start":"2026-05-05T10:19:31.669555Z","end":"2026-05-05T10:19:31.971389Z","steps":["trace[1872554232] 'process raft request' (duration: 301.797287ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:31.971461Z","caller":"traceutil/trace.go:171","msg":"trace[1543609753] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"302.287209ms","start":"2026-05-05T10:19:31.669142Z","end":"2026-05-05T10:19:31.971429Z","steps":["trace[1543609753] 'process raft request' (duration: 183.198751ms)","trace[1543609753] 'compare' (duration: 118.474323ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:19:31.971573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:31.669548Z","time spent":"301.949391ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:19:31.971497Z","caller":"traceutil/trace.go:171","msg":"trace[1371981291] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"301.996881ms","start":"2026-05-05T10:19:31.669491Z","end":"2026-05-05T10:19:31.971488Z","steps":["trace[1371981291] 'process raft request' (duration: 301.828207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:31.971615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:31.669112Z","time spent":"302.423882ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:19:31.971438Z","caller":"traceutil/trace.go:171","msg":"trace[825371745] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"301.980862ms","start":"2026-05-05T10:19:31.669437Z","end":"2026-05-05T10:19:31.971418Z","steps":["trace[825371745] 'process raft request' (duration: 301.822498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:31.971719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:31.669471Z","time spent":"302.174396ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:19:31.971772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:31.669412Z","time spent":"302.303029ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:19:47.792901Z","caller":"traceutil/trace.go:171","msg":"trace[252860754] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"425.783641ms","start":"2026-05-05T10:19:47.367093Z","end":"2026-05-05T10:19:47.792876Z","steps":["trace[252860754] 'process raft request' (duration: 425.586926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:47.79306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:47.367076Z","time spent":"425.905515ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:19:47.793992Z","caller":"traceutil/trace.go:171","msg":"trace[2019183996] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"161.753521ms","start":"2026-05-05T10:19:47.63222Z","end":"2026-05-05T10:19:47.793973Z","steps":["trace[2019183996] 'process raft request' (duration: 161.565807ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:00.951537Z","caller":"traceutil/trace.go:171","msg":"trace[2032673503] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"124.256911ms","start":"2026-05-05T10:20:00.827251Z","end":"2026-05-05T10:20:00.951508Z","steps":["trace[2032673503] 'process raft request' (duration: 124.113237ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:01.663197Z","caller":"traceutil/trace.go:171","msg":"trace[1270480062] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"193.129397ms","start":"2026-05-05T10:20:01.470028Z","end":"2026-05-05T10:20:01.663157Z","steps":["trace[1270480062] 'read index received' (duration: 191.366125ms)","trace[1270480062] 'applied index is now lower than readState.Index' (duration: 1.761312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:20:01.663357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.302141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:20:01.66343Z","caller":"traceutil/trace.go:171","msg":"trace[79005108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1192; }","duration":"193.432164ms","start":"2026-05-05T10:20:01.469982Z","end":"2026-05-05T10:20:01.663415Z","steps":["trace[79005108] 'agreement among raft nodes before linearized reading' (duration: 193.290411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:01.663512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.575069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:20:01.6636Z","caller":"traceutil/trace.go:171","msg":"trace[1384141971] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1192; }","duration":"100.741993ms","start":"2026-05-05T10:20:01.562832Z","end":"2026-05-05T10:20:01.663574Z","steps":["trace[1384141971] 'agreement among raft nodes before linearized reading' (duration: 100.563418ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:06.030742Z","caller":"traceutil/trace.go:171","msg":"trace[1680648868] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"135.813145ms","start":"2026-05-05T10:20:05.894897Z","end":"2026-05-05T10:20:06.030711Z","steps":["trace[1680648868] 'read index received' (duration: 135.664022ms)","trace[1680648868] 'applied index is now lower than readState.Index' (duration: 147.783µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:20:06.030965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.055071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T10:20:06.031028Z","caller":"traceutil/trace.go:171","msg":"trace[1002635853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1225; }","duration":"136.166894ms","start":"2026-05-05T10:20:05.89485Z","end":"2026-05-05T10:20:06.031017Z","steps":["trace[1002635853] 'agreement among raft nodes before linearized reading' (duration: 135.967329ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:06.030899Z","caller":"traceutil/trace.go:171","msg":"trace[1772653716] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"165.752906ms","start":"2026-05-05T10:20:05.865115Z","end":"2026-05-05T10:20:06.030868Z","steps":["trace[1772653716] 'process raft request' (duration: 165.460019ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:06.242569Z","caller":"traceutil/trace.go:171","msg":"trace[1640394753] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"206.805752ms","start":"2026-05-05T10:20:06.03573Z","end":"2026-05-05T10:20:06.242536Z","steps":["trace[1640394753] 'process raft request' (duration: 206.645218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:06.390709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.579683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:20:06.390949Z","caller":"traceutil/trace.go:171","msg":"trace[228844118] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1281; }","duration":"128.983943ms","start":"2026-05-05T10:20:06.26194Z","end":"2026-05-05T10:20:06.390924Z","steps":["trace[228844118] 'read index received' (duration: 16.104153ms)","trace[228844118] 'applied index is now lower than readState.Index' (duration: 112.87858ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:20:06.390937Z","caller":"traceutil/trace.go:171","msg":"trace[984656300] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"143.938298ms","start":"2026-05-05T10:20:06.246965Z","end":"2026-05-05T10:20:06.390904Z","steps":["trace[984656300] 'process raft request' (duration: 31.068248ms)","trace[984656300] 'compare' (duration: 112.45926ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:20:06.390987Z","caller":"traceutil/trace.go:171","msg":"trace[1464298177] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"143.697372ms","start":"2026-05-05T10:20:06.247254Z","end":"2026-05-05T10:20:06.390951Z","steps":["trace[1464298177] 'process raft request' (duration: 143.575339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:06.391141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.073792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T10:20:06.391185Z","caller":"traceutil/trace.go:171","msg":"trace[182819661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1228; }","duration":"123.166724ms","start":"2026-05-05T10:20:06.268007Z","end":"2026-05-05T10:20:06.391173Z","steps":["trace[182819661] 'agreement among raft nodes before linearized reading' (duration: 123.050612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:06.391254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.312471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-05T10:20:06.391264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.185934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T10:20:06.391288Z","caller":"traceutil/trace.go:171","msg":"trace[1566753769] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:1228; }","duration":"129.378463ms","start":"2026-05-05T10:20:06.2619Z","end":"2026-05-05T10:20:06.391279Z","steps":["trace[1566753769] 'agreement among raft nodes before linearized reading' (duration: 129.109387ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:06.391301Z","caller":"traceutil/trace.go:171","msg":"trace[497004888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1228; }","duration":"112.251606ms","start":"2026-05-05T10:20:06.279039Z","end":"2026-05-05T10:20:06.391291Z","steps":["trace[497004888] 'agreement among raft nodes before linearized reading' (duration: 112.087592ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:06.513939Z","caller":"traceutil/trace.go:171","msg":"trace[2116751350] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"118.904094ms","start":"2026-05-05T10:20:06.395014Z","end":"2026-05-05T10:20:06.513918Z","steps":["trace[2116751350] 'process raft request' (duration: 102.323511ms)","trace[2116751350] 'compare' (duration: 16.41419ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:20:06.514125Z","caller":"traceutil/trace.go:171","msg":"trace[1512487694] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"118.706579ms","start":"2026-05-05T10:20:06.395411Z","end":"2026-05-05T10:20:06.514118Z","steps":["trace[1512487694] 'process raft request' (duration: 118.476324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:07.188889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.124088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T10:20:07.188986Z","caller":"traceutil/trace.go:171","msg":"trace[386281115] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1231; }","duration":"311.286952ms","start":"2026-05-05T10:20:06.877679Z","end":"2026-05-05T10:20:07.188966Z","steps":["trace[386281115] 'count revisions from in-memory index tree' (duration: 311.007936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:07.189032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:06.877631Z","time spent":"311.389714ms","remote":"127.0.0.1:55678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-05-05T10:20:08.215165Z","caller":"traceutil/trace.go:171","msg":"trace[1958891510] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"106.431607ms","start":"2026-05-05T10:20:08.108709Z","end":"2026-05-05T10:20:08.215141Z","steps":["trace[1958891510] 'process raft request' (duration: 106.297234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:08.780941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.476383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:20:08.781168Z","caller":"traceutil/trace.go:171","msg":"trace[1323565457] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"307.575195ms","start":"2026-05-05T10:20:08.473576Z","end":"2026-05-05T10:20:08.781151Z","steps":["trace[1323565457] 'process raft request' (duration: 307.469692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:08.781237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:08.47355Z","time spent":"307.654846ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:20:08.781433Z","caller":"traceutil/trace.go:171","msg":"trace[142368637] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"530.037737ms","start":"2026-05-05T10:20:08.251384Z","end":"2026-05-05T10:20:08.781421Z","steps":["trace[142368637] 'process raft request' (duration: 250.99545ms)","trace[142368637] 'compare' (duration: 278.150646ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:20:08.78149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:08.251362Z","time spent":"530.100559ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:20:08.781563Z","caller":"traceutil/trace.go:171","msg":"trace[677332815] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"385.897464ms","start":"2026-05-05T10:20:08.395658Z","end":"2026-05-05T10:20:08.781556Z","steps":["trace[677332815] 'read index received' (duration: 106.669343ms)","trace[677332815] 'applied index is now lower than readState.Index' (duration: 279.227261ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:20:08.781775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.118319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T10:20:08.78182Z","caller":"traceutil/trace.go:171","msg":"trace[733078778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1235; }","duration":"386.201881ms","start":"2026-05-05T10:20:08.395606Z","end":"2026-05-05T10:20:08.781808Z","steps":["trace[733078778] 'agreement among raft nodes before linearized reading' (duration: 386.078498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:08.781842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:08.395586Z","time spent":"386.252353ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T10:20:08.781839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.14097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T10:20:08.781891Z","caller":"traceutil/trace.go:171","msg":"trace[1142570403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"386.230772ms","start":"2026-05-05T10:20:08.395642Z","end":"2026-05-05T10:20:08.781873Z","steps":["trace[1142570403] 'agreement among raft nodes before linearized reading' (duration: 386.073308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:08.781877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.953778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T10:20:08.781929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.233194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T10:20:08.781933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.498507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T10:20:08.781937Z","caller":"traceutil/trace.go:171","msg":"trace[311818431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1235; }","duration":"264.0482ms","start":"2026-05-05T10:20:08.517876Z","end":"2026-05-05T10:20:08.781924Z","steps":["trace[311818431] 'agreement among raft nodes before linearized reading' (duration: 263.864896ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:08.781953Z","caller":"traceutil/trace.go:171","msg":"trace[205869886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"309.291116ms","start":"2026-05-05T10:20:08.472655Z","end":"2026-05-05T10:20:08.781946Z","steps":["trace[205869886] 'agreement among raft nodes before linearized reading' (duration: 309.252645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:08.781934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:08.395621Z","time spent":"386.302574ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T10:20:08.781969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:08.472637Z","time spent":"309.329266ms","remote":"127.0.0.1:36908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T10:20:08.781966Z","caller":"traceutil/trace.go:171","msg":"trace[405228420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1235; }","duration":"263.544369ms","start":"2026-05-05T10:20:08.518412Z","end":"2026-05-05T10:20:08.781957Z","steps":["trace[405228420] 'agreement among raft nodes before linearized reading' (duration: 263.449357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:09.285589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.714541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:20:09.285925Z","caller":"traceutil/trace.go:171","msg":"trace[822905916] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"500.914436ms","start":"2026-05-05T10:20:08.784958Z","end":"2026-05-05T10:20:09.285873Z","steps":["trace[822905916] 'process raft request' (duration: 322.831806ms)","trace[822905916] 'compare' (duration: 177.608888ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:20:09.286028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:08.784938Z","time spent":"501.054578ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:20:09.362226Z","caller":"traceutil/trace.go:171","msg":"trace[1300355201] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"576.147932ms","start":"2026-05-05T10:20:08.786053Z","end":"2026-05-05T10:20:09.3622Z","steps":["trace[1300355201] 'process raft request' (duration: 576.107401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:09.362373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:08.786034Z","time spent":"576.267405ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:20:09.362811Z","caller":"traceutil/trace.go:171","msg":"trace[1186635066] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"577.526315ms","start":"2026-05-05T10:20:08.785272Z","end":"2026-05-05T10:20:09.362799Z","steps":["trace[1186635066] 'process raft request' (duration: 576.726066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:09.362883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:08.78525Z","time spent":"577.600287ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:20:09.363018Z","caller":"traceutil/trace.go:171","msg":"trace[410759634] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"577.684128ms","start":"2026-05-05T10:20:08.785325Z","end":"2026-05-05T10:20:09.363009Z","steps":["trace[410759634] 'process raft request' (duration: 576.798727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:09.363065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:20:08.785315Z","time spent":"577.72533ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:20:10.527317Z","caller":"traceutil/trace.go:171","msg":"trace[1008122179] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"125.824609ms","start":"2026-05-05T10:20:10.401442Z","end":"2026-05-05T10:20:10.527266Z","steps":["trace[1008122179] 'process raft request' (duration: 115.123634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:11.019787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.221233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T10:20:11.019835Z","caller":"traceutil/trace.go:171","msg":"trace[1926029180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1246; }","duration":"232.321275ms","start":"2026-05-05T10:20:10.787503Z","end":"2026-05-05T10:20:11.019824Z","steps":["trace[1926029180] 'range keys from in-memory index tree' (duration: 232.086871ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:18.420786Z","caller":"traceutil/trace.go:171","msg":"trace[1323928117] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"209.251884ms","start":"2026-05-05T10:20:18.211514Z","end":"2026-05-05T10:20:18.420765Z","steps":["trace[1323928117] 'process raft request' (duration: 209.125251ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:18.918168Z","caller":"traceutil/trace.go:171","msg":"trace[841863720] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"286.936614ms","start":"2026-05-05T10:20:18.631196Z","end":"2026-05-05T10:20:18.918133Z","steps":["trace[841863720] 'process raft request' (duration: 286.738779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:31.770682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.719375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:20:31.770969Z","caller":"traceutil/trace.go:171","msg":"trace[1107809731] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"109.801409ms","start":"2026-05-05T10:20:31.661116Z","end":"2026-05-05T10:20:31.770917Z","steps":["trace[1107809731] 'compare' (duration: 100.50704ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:31.771007Z","caller":"traceutil/trace.go:171","msg":"trace[492710343] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"108.634352ms","start":"2026-05-05T10:20:31.662344Z","end":"2026-05-05T10:20:31.770979Z","steps":["trace[492710343] 'process raft request' (duration: 108.531859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:41.627795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.564936ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T10:20:41.627913Z","caller":"traceutil/trace.go:171","msg":"trace[1336876326] linearizableReadLoop","detail":"{readStateIndex:1477; appliedIndex:1476; }","duration":"103.211264ms","start":"2026-05-05T10:20:41.524685Z","end":"2026-05-05T10:20:41.627896Z","steps":["trace[1336876326] 'read index received' (duration: 26.401µs)","trace[1336876326] 'applied index is now lower than readState.Index' (duration: 103.183283ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:20:41.628107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.396998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T10:20:41.628194Z","caller":"traceutil/trace.go:171","msg":"trace[851772869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1414; }","duration":"103.525571ms","start":"2026-05-05T10:20:41.524647Z","end":"2026-05-05T10:20:41.628173Z","steps":["trace[851772869] 'agreement among raft nodes before linearized reading' (duration: 103.291386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:03.600136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.492889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:21:03.600784Z","caller":"traceutil/trace.go:171","msg":"trace[1746301764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1515; }","duration":"128.153574ms","start":"2026-05-05T10:21:03.47261Z","end":"2026-05-05T10:21:03.600764Z","steps":["trace[1746301764] 'range keys from in-memory index tree' (duration: 127.412037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:04.145221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.519869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:21:04.145462Z","caller":"traceutil/trace.go:171","msg":"trace[2091560320] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"243.463444ms","start":"2026-05-05T10:21:03.901968Z","end":"2026-05-05T10:21:04.145431Z","steps":["trace[2091560320] 'process raft request' (duration: 96.642888ms)","trace[2091560320] 'compare' (duration: 146.415606ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:04.14585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.997617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-05T10:21:04.145941Z","caller":"traceutil/trace.go:171","msg":"trace[1481359670] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1518; }","duration":"127.111289ms","start":"2026-05-05T10:21:04.018807Z","end":"2026-05-05T10:21:04.145918Z","steps":["trace[1481359670] 'agreement among raft nodes before linearized reading' (duration: 126.743681ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:04.145397Z","caller":"traceutil/trace.go:171","msg":"trace[2043878157] linearizableReadLoop","detail":"{readStateIndex:1585; appliedIndex:1584; }","duration":"126.567827ms","start":"2026-05-05T10:21:04.018812Z","end":"2026-05-05T10:21:04.14538Z","steps":["trace[2043878157] 'read index received' (duration: 36.571µs)","trace[2043878157] 'applied index is now lower than readState.Index' (duration: 126.529786ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:04.483275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.621945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:21:04.483536Z","caller":"traceutil/trace.go:171","msg":"trace[111403514] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"332.476031ms","start":"2026-05-05T10:21:04.151034Z","end":"2026-05-05T10:21:04.48351Z","steps":["trace[111403514] 'process raft request' (duration: 332.388819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:04.483631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:04.151018Z","time spent":"332.576314ms","remote":"127.0.0.1:37220","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-05-05T10:21:04.48363Z","caller":"traceutil/trace.go:171","msg":"trace[1846994117] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"332.547973ms","start":"2026-05-05T10:21:04.151048Z","end":"2026-05-05T10:21:04.483596Z","steps":["trace[1846994117] 'process raft request' (duration: 127.453657ms)","trace[1846994117] 'compare' (duration: 204.40115ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:04.483825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:04.151032Z","time spent":"332.691066ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:21:09.362535Z","caller":"traceutil/trace.go:171","msg":"trace[94846396] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"110.481326ms","start":"2026-05-05T10:21:09.252035Z","end":"2026-05-05T10:21:09.362517Z","steps":["trace[94846396] 'process raft request' (duration: 109.999944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:21.721745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.746288ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T10:21:30.831812Z","caller":"traceutil/trace.go:171","msg":"trace[1094970454] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"131.81426ms","start":"2026-05-05T10:21:30.69996Z","end":"2026-05-05T10:21:30.831775Z","steps":["trace[1094970454] 'process raft request' (duration: 83.535317ms)","trace[1094970454] 'compare' (duration: 48.16054ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:40.622489Z","caller":"traceutil/trace.go:171","msg":"trace[1463498514] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"117.703186ms","start":"2026-05-05T10:21:40.504758Z","end":"2026-05-05T10:21:40.622461Z","steps":["trace[1463498514] 'process raft request' (duration: 68.323667ms)","trace[1463498514] 'compare' (duration: 49.244006ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:53.308417Z","caller":"traceutil/trace.go:171","msg":"trace[612375907] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"158.541972ms","start":"2026-05-05T10:21:53.149821Z","end":"2026-05-05T10:21:53.308363Z","steps":["trace[612375907] 'process raft request' (duration: 109.580993ms)","trace[612375907] 'compare' (duration: 48.851217ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:22:34.544284Z","caller":"traceutil/trace.go:171","msg":"trace[873533610] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"111.430899ms","start":"2026-05-05T10:22:34.432833Z","end":"2026-05-05T10:22:34.544264Z","steps":["trace[873533610] 'process raft request' (duration: 111.316336ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:22:34.729507Z","caller":"traceutil/trace.go:171","msg":"trace[1266641434] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"103.0009ms","start":"2026-05-05T10:22:34.626473Z","end":"2026-05-05T10:22:34.729474Z","steps":["trace[1266641434] 'process raft request' (duration: 17.476703ms)","trace[1266641434] 'compare' (duration: 85.23902ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:22:34.938187Z","caller":"traceutil/trace.go:171","msg":"trace[1388000741] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"127.237441ms","start":"2026-05-05T10:22:34.810924Z","end":"2026-05-05T10:22:34.938162Z","steps":["trace[1388000741] 'process raft request' (duration: 60.509247ms)","trace[1388000741] 'compare' (duration: 66.591951ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:22:45.546471Z","caller":"traceutil/trace.go:171","msg":"trace[202063725] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"100.86837ms","start":"2026-05-05T10:22:45.445561Z","end":"2026-05-05T10:22:45.546429Z","steps":["trace[202063725] 'process raft request' (duration: 90.481145ms)","trace[202063725] 'compare' (duration: 10.086258ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:22:55.492431Z","caller":"traceutil/trace.go:171","msg":"trace[1686073581] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"110.428915ms","start":"2026-05-05T10:22:55.38198Z","end":"2026-05-05T10:22:55.492409Z","steps":["trace[1686073581] 'process raft request' (duration: 110.278091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:22:59.901074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.900943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-05T10:22:59.901195Z","caller":"traceutil/trace.go:171","msg":"trace[989062527] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2047; }","duration":"121.060636ms","start":"2026-05-05T10:22:59.780109Z","end":"2026-05-05T10:22:59.901169Z","steps":["trace[989062527] 'range keys from in-memory index tree' (duration: 120.654466ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:23:25.166227Z","caller":"traceutil/trace.go:171","msg":"trace[1396867558] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2264; }","duration":"123.863632ms","start":"2026-05-05T10:23:25.042323Z","end":"2026-05-05T10:23:25.166186Z","steps":["trace[1396867558] 'read index received' (duration: 116.782885ms)","trace[1396867558] 'applied index is now lower than readState.Index' (duration: 7.078967ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:23:25.166525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.179529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T10:23:25.16635Z","caller":"traceutil/trace.go:171","msg":"trace[1397752935] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"176.831261ms","start":"2026-05-05T10:23:24.989479Z","end":"2026-05-05T10:23:25.16631Z","steps":["trace[1397752935] 'process raft request' (duration: 169.700513ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:23:25.166652Z","caller":"traceutil/trace.go:171","msg":"trace[458655992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2169; }","duration":"124.360033ms","start":"2026-05-05T10:23:25.042264Z","end":"2026-05-05T10:23:25.166624Z","steps":["trace[458655992] 'agreement among raft nodes before linearized reading' (duration: 124.086037ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:23:39.178461Z","caller":"traceutil/trace.go:171","msg":"trace[1528838310] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"164.558265ms","start":"2026-05-05T10:23:39.013873Z","end":"2026-05-05T10:23:39.178431Z","steps":["trace[1528838310] 'process raft request' (duration: 164.279249ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:24:16.773193Z","caller":"traceutil/trace.go:171","msg":"trace[323854482] linearizableReadLoop","detail":"{readStateIndex:2547; appliedIndex:2546; }","duration":"125.052985ms","start":"2026-05-05T10:24:16.648108Z","end":"2026-05-05T10:24:16.773161Z","steps":["trace[323854482] 'read index received' (duration: 123.57134ms)","trace[323854482] 'applied index is now lower than readState.Index' (duration: 1.480415ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:24:16.773363Z","caller":"traceutil/trace.go:171","msg":"trace[943554771] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"173.902045ms","start":"2026-05-05T10:24:16.599444Z","end":"2026-05-05T10:24:16.773346Z","steps":["trace[943554771] 'process raft request' (duration: 172.153204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:24:16.773553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.78287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T10:24:16.773566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.444404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:1 size:201"} {"level":"warn","ts":"2026-05-05T10:24:16.77357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.547465ms","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-05-05T10:24:16.773657Z","caller":"traceutil/trace.go:171","msg":"trace[559212511] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2439; }","duration":"113.962354ms","start":"2026-05-05T10:24:16.659676Z","end":"2026-05-05T10:24:16.773639Z","steps":["trace[559212511] 'agreement among raft nodes before linearized reading' (duration: 113.807031ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:24:16.773701Z","caller":"traceutil/trace.go:171","msg":"trace[1233574683] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:1; response_revision:2439; }","duration":"125.604938ms","start":"2026-05-05T10:24:16.648057Z","end":"2026-05-05T10:24:16.773662Z","steps":["trace[1233574683] 'agreement among raft nodes before linearized reading' (duration: 125.430654ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:24:16.773698Z","caller":"traceutil/trace.go:171","msg":"trace[202572108] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:2439; }","duration":"102.696758ms","start":"2026-05-05T10:24:16.670981Z","end":"2026-05-05T10:24:16.773678Z","steps":["trace[202572108] 'agreement among raft nodes before linearized reading' (duration: 102.511994ms)"],"step_count":1}