{"level":"warn","ts":"2026-06-02T23:53:15.423943Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T23:53:15.424088Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-06-02T23:53:15.424164Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T23:53:15.424173Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-06-02T23:53:15.424193Z","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-06-02T23:53:15.424919Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-06-02T23:53:15.425005Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-06-02T23:53:15.563727Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"138.496339ms"} {"level":"info","ts":"2026-06-02T23:53:15.580222Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-06-02T23:53:15.580552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T23:53:15.580678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-06-02T23:53:15.58078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T23:53:15.580882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-06-02T23:53:15.581122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-06-02T23:53:15.589546Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T23:53:15.591818Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T23:53:15.596343Z","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-06-02T23:53:15.599427Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T23:53:15.600767Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T23:53:15.600965Z","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-06-02T23:53:15.60105Z","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-06-02T23:53:15.601068Z","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-06-02T23:53:15.602291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-06-02T23:53:15.602494Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-06-02T23:53:15.604573Z","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-06-02T23:53:15.60468Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-06-02T23:53:15.604754Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-06-02T23:53:15.604843Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T23:53:15.604875Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T23:53:15.681577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T23:53:15.68163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T23:53:15.68167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-06-02T23:53:15.681687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-06-02T23:53:15.681698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-06-02T23:53:15.681714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-06-02T23:53:15.68173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-06-02T23:53:15.682891Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:53:15.684101Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T23:53:15.684267Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T23:53:15.684306Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T23:53:15.684424Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T23:53:15.684485Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T23:53:15.685486Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:53:15.68561Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:53:15.68565Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:53:15.686446Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-06-02T23:53:15.690179Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T23:53:23.431224Z","caller":"traceutil/trace.go:171","msg":"trace[726013647] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:302; }","duration":"188.513797ms","start":"2026-06-02T23:53:23.242686Z","end":"2026-06-02T23:53:23.4312Z","steps":["trace[726013647] 'read index received' (duration: 188.319763ms)","trace[726013647] 'applied index is now lower than readState.Index' (duration: 193.294µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:23.431367Z","caller":"traceutil/trace.go:171","msg":"trace[1691666065] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"192.69446ms","start":"2026-06-02T23:53:23.238624Z","end":"2026-06-02T23:53:23.431318Z","steps":["trace[1691666065] 'process raft request' (duration: 192.457405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:23.431415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.675881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-06-02T23:53:23.431575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.588371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-06-02T23:53:23.43161Z","caller":"traceutil/trace.go:171","msg":"trace[595329563] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:291; }","duration":"188.900236ms","start":"2026-06-02T23:53:23.242682Z","end":"2026-06-02T23:53:23.431583Z","steps":["trace[595329563] 'agreement among raft nodes before linearized reading' (duration: 188.64978ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:23.431643Z","caller":"traceutil/trace.go:171","msg":"trace[142384047] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:291; }","duration":"138.693574ms","start":"2026-06-02T23:53:23.29293Z","end":"2026-06-02T23:53:23.431624Z","steps":["trace[142384047] 'agreement among raft nodes before linearized reading' (duration: 138.566641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:24.972204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22209238s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:53:24.97231Z","caller":"traceutil/trace.go:171","msg":"trace[1954152714] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:303; }","duration":"953.440598ms","start":"2026-06-02T23:53:24.018853Z","end":"2026-06-02T23:53:24.972293Z","steps":["trace[1954152714] 'read index received' (duration: 31.571µs)","trace[1954152714] 'applied index is now lower than readState.Index' (duration: 953.407787ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:24.97236Z","caller":"traceutil/trace.go:171","msg":"trace[182721804] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"1.53661725s","start":"2026-06-02T23:53:23.435702Z","end":"2026-06-02T23:53:24.972319Z","steps":["trace[182721804] 'process raft request' (duration: 313.855265ms)","trace[182721804] 'compare' (duration: 1.221959628s)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:53:24.972485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.625522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-02T23:53:24.972491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:23.435683Z","time spent":"1.536746263s","remote":"127.0.0.1:48914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:53:24.972535Z","caller":"traceutil/trace.go:171","msg":"trace[1386232087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:292; }","duration":"953.713994ms","start":"2026-06-02T23:53:24.018811Z","end":"2026-06-02T23:53:24.972525Z","steps":["trace[1386232087] 'agreement among raft nodes before linearized reading' (duration: 953.588121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:24.972577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:24.018792Z","time spent":"953.773705ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T23:53:24.972782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.969957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-02T23:53:24.972851Z","caller":"traceutil/trace.go:171","msg":"trace[1990296255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:292; }","duration":"717.07809ms","start":"2026-06-02T23:53:24.255751Z","end":"2026-06-02T23:53:24.972829Z","steps":["trace[1990296255] 'agreement among raft nodes before linearized reading' (duration: 716.953437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:24.972909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:24.255703Z","time spent":"717.179602ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T23:53:25.981299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.924859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:53:25.981511Z","caller":"traceutil/trace.go:171","msg":"trace[1456270226] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:304; }","duration":"1.003843192s","start":"2026-06-02T23:53:24.977652Z","end":"2026-06-02T23:53:25.981495Z","steps":["trace[1456270226] 'read index received' (duration: 159.530884ms)","trace[1456270226] 'applied index is now lower than readState.Index' (duration: 844.311398ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:25.981554Z","caller":"traceutil/trace.go:171","msg":"trace[1780822842] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"1.004870996s","start":"2026-06-02T23:53:24.976653Z","end":"2026-06-02T23:53:25.981524Z","steps":["trace[1780822842] 'process raft request' (duration: 1.004760474s)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:25.981603Z","caller":"traceutil/trace.go:171","msg":"trace[1168793951] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"1.00372129s","start":"2026-06-02T23:53:24.97786Z","end":"2026-06-02T23:53:25.981581Z","steps":["trace[1168793951] 'process raft request' (duration: 1.003602928s)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:25.981574Z","caller":"traceutil/trace.go:171","msg":"trace[198852712] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"1.005154362s","start":"2026-06-02T23:53:24.976356Z","end":"2026-06-02T23:53:25.981511Z","steps":["trace[198852712] 'process raft request' (duration: 160.813583ms)","trace[198852712] 'compare' (duration: 843.794237ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:53:25.981702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:24.976625Z","time spent":"1.004997268s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:53:25.98171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:24.977853Z","time spent":"1.003803072s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:53:25.981804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.900573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-06-02T23:53:25.981853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:24.976335Z","time spent":"1.005363667s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:53:25.981638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003981166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-06-02T23:53:25.981919Z","caller":"traceutil/trace.go:171","msg":"trace[403397983] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:295; }","duration":"1.004278353s","start":"2026-06-02T23:53:24.977627Z","end":"2026-06-02T23:53:25.981905Z","steps":["trace[403397983] 'agreement among raft nodes before linearized reading' (duration: 1.003958275s)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:25.981916Z","caller":"traceutil/trace.go:171","msg":"trace[769324862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:295; }","duration":"545.069717ms","start":"2026-06-02T23:53:25.436822Z","end":"2026-06-02T23:53:25.981892Z","steps":["trace[769324862] 'agreement among raft nodes before linearized reading' (duration: 544.843412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:25.981973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:24.97762Z","time spent":"1.004335874s","remote":"127.0.0.1:48914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-06-02T23:53:25.981986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:25.43677Z","time spent":"545.20044ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T23:53:31.075905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.034045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:53:31.076054Z","caller":"traceutil/trace.go:171","msg":"trace[1443555374] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"339.772729ms","start":"2026-06-02T23:53:30.736223Z","end":"2026-06-02T23:53:31.075996Z","steps":["trace[1443555374] 'process raft request' (duration: 124.554ms)","trace[1443555374] 'compare' (duration: 214.893392ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:53:31.076124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:30.736201Z","time spent":"339.894872ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:53:46.237208Z","caller":"traceutil/trace.go:171","msg":"trace[1217697028] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"127.964345ms","start":"2026-06-02T23:53:46.109223Z","end":"2026-06-02T23:53:46.237188Z","steps":["trace[1217697028] 'read index received' (duration: 127.793332ms)","trace[1217697028] 'applied index is now lower than readState.Index' (duration: 170.193µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:46.23726Z","caller":"traceutil/trace.go:171","msg":"trace[160332177] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"130.478351ms","start":"2026-06-02T23:53:46.106753Z","end":"2026-06-02T23:53:46.237231Z","steps":["trace[160332177] 'process raft request' (duration: 130.318708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:46.237326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.087028ms","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-06-02T23:53:46.237348Z","caller":"traceutil/trace.go:171","msg":"trace[833897709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"128.137529ms","start":"2026-06-02T23:53:46.109204Z","end":"2026-06-02T23:53:46.237341Z","steps":["trace[833897709] 'agreement among raft nodes before linearized reading' (duration: 128.083378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:46.237439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.856024ms","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-06-02T23:53:46.237457Z","caller":"traceutil/trace.go:171","msg":"trace[700655000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"123.904945ms","start":"2026-06-02T23:53:46.113548Z","end":"2026-06-02T23:53:46.237453Z","steps":["trace[700655000] 'agreement among raft nodes before linearized reading' (duration: 123.876655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:46.237428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.875675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-02T23:53:46.237494Z","caller":"traceutil/trace.go:171","msg":"trace[726362801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:431; }","duration":"123.977007ms","start":"2026-06-02T23:53:46.113501Z","end":"2026-06-02T23:53:46.237478Z","steps":["trace[726362801] 'agreement among raft nodes before linearized reading' (duration: 123.835374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:55.064821Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291913764651466,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T23:53:55.17345Z","caller":"traceutil/trace.go:171","msg":"trace[1050331958] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"614.721437ms","start":"2026-06-02T23:53:54.558705Z","end":"2026-06-02T23:53:55.173427Z","steps":["trace[1050331958] 'process raft request' (duration: 614.332468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:55.173605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:54.558687Z","time spent":"614.836809ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:53:55.18867Z","caller":"traceutil/trace.go:171","msg":"trace[195765255] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"592.945884ms","start":"2026-06-02T23:53:54.595591Z","end":"2026-06-02T23:53:55.188537Z","steps":["trace[195765255] 'process raft request' (duration: 589.596196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:55.188889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:54.595572Z","time spent":"593.178368ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:53:55.188699Z","caller":"traceutil/trace.go:171","msg":"trace[526074007] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:607; }","duration":"624.41093ms","start":"2026-06-02T23:53:54.564256Z","end":"2026-06-02T23:53:55.188667Z","steps":["trace[526074007] 'read index received' (duration: 608.746908ms)","trace[526074007] 'applied index is now lower than readState.Index' (duration: 15.662432ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:53:55.189492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.237539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-06-02T23:53:55.18953Z","caller":"traceutil/trace.go:171","msg":"trace[2071189077] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:586; }","duration":"625.316151ms","start":"2026-06-02T23:53:54.564203Z","end":"2026-06-02T23:53:55.189519Z","steps":["trace[2071189077] 'agreement among raft nodes before linearized reading' (duration: 624.530993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:55.189616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:54.564185Z","time spent":"625.419913ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":65458,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-06-02T23:53:55.189696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.697931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-s2xqx\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-06-02T23:53:55.189812Z","caller":"traceutil/trace.go:171","msg":"trace[1380539242] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-s2xqx; range_end:; response_count:1; response_revision:586; }","duration":"470.903255ms","start":"2026-06-02T23:53:54.718884Z","end":"2026-06-02T23:53:55.189788Z","steps":["trace[1380539242] 'agreement among raft nodes before linearized reading' (duration: 470.396794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:55.189887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:54.718866Z","time spent":"470.997307ms","remote":"127.0.0.1:48894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4910,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-s2xqx\" "} {"level":"warn","ts":"2026-06-02T23:53:55.190431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.572642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:53:55.19048Z","caller":"traceutil/trace.go:171","msg":"trace[1409081028] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:586; }","duration":"242.651184ms","start":"2026-06-02T23:53:54.947814Z","end":"2026-06-02T23:53:55.190465Z","steps":["trace[1409081028] 'agreement among raft nodes before linearized reading' (duration: 242.584563ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:55.190704Z","caller":"traceutil/trace.go:171","msg":"trace[576849198] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"439.96046ms","start":"2026-06-02T23:53:54.7489Z","end":"2026-06-02T23:53:55.188861Z","steps":["trace[576849198] 'process raft request' (duration: 439.272864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:55.190773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:54.748878Z","time spent":"441.868815ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:53:56.477914Z","caller":"traceutil/trace.go:171","msg":"trace[505302999] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"131.752023ms","start":"2026-06-02T23:53:56.346137Z","end":"2026-06-02T23:53:56.477889Z","steps":["trace[505302999] 'process raft request' (duration: 100.246175ms)","trace[505302999] 'compare' (duration: 31.1709ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:56.478325Z","caller":"traceutil/trace.go:171","msg":"trace[320858387] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"130.282929ms","start":"2026-06-02T23:53:56.34803Z","end":"2026-06-02T23:53:56.478313Z","steps":["trace[320858387] 'process raft request' (duration: 129.689435ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:02.234593Z","caller":"traceutil/trace.go:171","msg":"trace[657244797] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"424.715617ms","start":"2026-06-02T23:54:01.809855Z","end":"2026-06-02T23:54:02.234571Z","steps":["trace[657244797] 'read index received' (duration: 424.654836ms)","trace[657244797] 'applied index is now lower than readState.Index' (duration: 59.751µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:54:02.234819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.950413ms","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-06-02T23:54:02.234858Z","caller":"traceutil/trace.go:171","msg":"trace[83897715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:653; }","duration":"425.035435ms","start":"2026-06-02T23:54:01.809811Z","end":"2026-06-02T23:54:02.234847Z","steps":["trace[83897715] 'agreement among raft nodes before linearized reading' (duration: 424.851641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:02.234907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:01.809791Z","time spent":"425.104557ms","remote":"127.0.0.1:48974","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-06-02T23:54:02.235021Z","caller":"traceutil/trace.go:171","msg":"trace[1752118157] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"489.547355ms","start":"2026-06-02T23:54:01.745159Z","end":"2026-06-02T23:54:02.234706Z","steps":["trace[1752118157] 'process raft request' (duration: 489.269678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:02.235317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:01.74513Z","time spent":"489.984695ms","remote":"127.0.0.1:48894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:54:02.285866Z","caller":"traceutil/trace.go:171","msg":"trace[982905049] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"382.244827ms","start":"2026-06-02T23:54:01.903602Z","end":"2026-06-02T23:54:02.285847Z","steps":["trace[982905049] 'process raft request' (duration: 382.145545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:02.286011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:01.903582Z","time spent":"382.37142ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:54:02.828887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291913764651694,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T23:54:03.321697Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.03591257s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T23:54:03.420836Z","caller":"traceutil/trace.go:171","msg":"trace[125083594] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"1.092811424s","start":"2026-06-02T23:54:02.327994Z","end":"2026-06-02T23:54:03.420806Z","steps":["trace[125083594] 'read index received' (duration: 993.857359ms)","trace[125083594] 'applied index is now lower than readState.Index' (duration: 98.952735ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:54:03.420986Z","caller":"traceutil/trace.go:171","msg":"trace[1657855945] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.181355539s","start":"2026-06-02T23:54:02.239599Z","end":"2026-06-02T23:54:03.420954Z","steps":["trace[1657855945] 'process raft request' (duration: 1.082305582s)","trace[1657855945] 'compare' (duration: 98.674339ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:54:03.421051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09305065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-02T23:54:03.421052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.130361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:54:03.421087Z","caller":"traceutil/trace.go:171","msg":"trace[618347727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"1.093137132s","start":"2026-06-02T23:54:02.327938Z","end":"2026-06-02T23:54:03.421075Z","steps":["trace[618347727] 'agreement among raft nodes before linearized reading' (duration: 1.09304292s)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:03.42109Z","caller":"traceutil/trace.go:171","msg":"trace[589310355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"310.204223ms","start":"2026-06-02T23:54:03.110875Z","end":"2026-06-02T23:54:03.421079Z","steps":["trace[589310355] 'agreement among raft nodes before linearized reading' (duration: 310.143442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:03.421111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:03.110849Z","time spent":"310.257475ms","remote":"127.0.0.1:48684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T23:54:03.421115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:02.327919Z","time spent":"1.093188683s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T23:54:03.421117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09001567s","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-06-02T23:54:03.421159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:02.239581Z","time spent":"1.181465271s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:54:03.421155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.824235ms","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-06-02T23:54:03.421173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061889871s","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-06-02T23:54:03.421209Z","caller":"traceutil/trace.go:171","msg":"trace[589337810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"1.090122532s","start":"2026-06-02T23:54:02.331055Z","end":"2026-06-02T23:54:03.421177Z","steps":["trace[589337810] 'agreement among raft nodes before linearized reading' (duration: 1.089954799s)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:03.421231Z","caller":"traceutil/trace.go:171","msg":"trace[2140013509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"914.916777ms","start":"2026-06-02T23:54:02.506294Z","end":"2026-06-02T23:54:03.421211Z","steps":["trace[2140013509] 'agreement among raft nodes before linearized reading' (duration: 914.719013ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:03.421248Z","caller":"traceutil/trace.go:171","msg":"trace[256891328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"1.062007883s","start":"2026-06-02T23:54:02.359226Z","end":"2026-06-02T23:54:03.421233Z","steps":["trace[256891328] 'agreement among raft nodes before linearized reading' (duration: 1.061824028s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:03.421263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:02.33104Z","time spent":"1.090209344s","remote":"127.0.0.1:48974","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-06-02T23:54:03.42127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:02.506237Z","time spent":"915.02528ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T23:54:03.421285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:02.359208Z","time spent":"1.062068475s","remote":"127.0.0.1:48974","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":"info","ts":"2026-06-02T23:54:03.564784Z","caller":"traceutil/trace.go:171","msg":"trace[867801778] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"138.784875ms","start":"2026-06-02T23:54:03.425976Z","end":"2026-06-02T23:54:03.56476Z","steps":["trace[867801778] 'process raft request' (duration: 138.700443ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:03.564942Z","caller":"traceutil/trace.go:171","msg":"trace[787269008] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"138.980829ms","start":"2026-06-02T23:54:03.42595Z","end":"2026-06-02T23:54:03.564931Z","steps":["trace[787269008] 'process raft request' (duration: 122.245483ms)","trace[787269008] 'compare' (duration: 16.382318ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:54:03.565121Z","caller":"traceutil/trace.go:171","msg":"trace[1780768642] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"139.041471ms","start":"2026-06-02T23:54:03.426024Z","end":"2026-06-02T23:54:03.565065Z","steps":["trace[1780768642] 'process raft request' (duration: 138.690783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:05.157413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.649542ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-02T23:54:06.86518Z","caller":"traceutil/trace.go:171","msg":"trace[127233367] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"102.318243ms","start":"2026-06-02T23:54:06.762838Z","end":"2026-06-02T23:54:06.865156Z","steps":["trace[127233367] 'process raft request' (duration: 102.17268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:07.487915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.319913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:54:07.488154Z","caller":"traceutil/trace.go:171","msg":"trace[1640299875] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"158.47288ms","start":"2026-06-02T23:54:07.329657Z","end":"2026-06-02T23:54:07.488129Z","steps":["trace[1640299875] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/kube-system/cilium-8xjvn.18b568caa946b2f0; req_size:719; } (duration: 147.21172ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:07.951527Z","caller":"traceutil/trace.go:171","msg":"trace[1430404598] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"126.283756ms","start":"2026-06-02T23:54:07.825213Z","end":"2026-06-02T23:54:07.951497Z","steps":["trace[1430404598] 'process raft request' (duration: 110.00382ms)","trace[1430404598] 'compare' (duration: 15.99682ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:54:17.949946Z","caller":"traceutil/trace.go:171","msg":"trace[959510067] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"118.063336ms","start":"2026-06-02T23:54:17.831838Z","end":"2026-06-02T23:54:17.949901Z","steps":["trace[959510067] 'process raft request' (duration: 117.819181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:22.100139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291913764651934,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T23:54:22.216293Z","caller":"traceutil/trace.go:171","msg":"trace[1526075835] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"936.415524ms","start":"2026-06-02T23:54:21.279848Z","end":"2026-06-02T23:54:22.216264Z","steps":["trace[1526075835] 'process raft request' (duration: 936.26306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:22.216505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:21.279823Z","time spent":"936.549547ms","remote":"127.0.0.1:48878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:54:22.217155Z","caller":"traceutil/trace.go:171","msg":"trace[1761703780] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:804; }","duration":"617.823497ms","start":"2026-06-02T23:54:21.599303Z","end":"2026-06-02T23:54:22.217126Z","steps":["trace[1761703780] 'read index received' (duration: 616.830464ms)","trace[1761703780] 'applied index is now lower than readState.Index' (duration: 985.933µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:54:22.217573Z","caller":"traceutil/trace.go:171","msg":"trace[358526070] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"366.020333ms","start":"2026-06-02T23:54:21.851536Z","end":"2026-06-02T23:54:22.217556Z","steps":["trace[358526070] 'process raft request' (duration: 365.449549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:22.217708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:21.851511Z","time spent":"366.111954ms","remote":"127.0.0.1:48956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:54:22.218072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.86032ms","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-06-02T23:54:22.218143Z","caller":"traceutil/trace.go:171","msg":"trace[490377682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"611.974342ms","start":"2026-06-02T23:54:21.606149Z","end":"2026-06-02T23:54:22.218123Z","steps":["trace[490377682] 'agreement among raft nodes before linearized reading' (duration: 611.743916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:22.218186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:21.606136Z","time spent":"612.040213ms","remote":"127.0.0.1:48974","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-06-02T23:54:22.2182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.618349ms","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-06-02T23:54:22.218254Z","caller":"traceutil/trace.go:171","msg":"trace[1100909732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"532.694201ms","start":"2026-06-02T23:54:21.685544Z","end":"2026-06-02T23:54:22.218238Z","steps":["trace[1100909732] 'agreement among raft nodes before linearized reading' (duration: 532.527988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:22.218297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:21.685534Z","time spent":"532.752683ms","remote":"127.0.0.1:48974","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-06-02T23:54:22.218325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.854422ms","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-06-02T23:54:22.218381Z","caller":"traceutil/trace.go:171","msg":"trace[409216698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:775; }","duration":"528.931614ms","start":"2026-06-02T23:54:21.689434Z","end":"2026-06-02T23:54:22.218365Z","steps":["trace[409216698] 'agreement among raft nodes before linearized reading' (duration: 528.767941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:22.218424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:21.68942Z","time spent":"528.993956ms","remote":"127.0.0.1:48974","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-06-02T23:54:22.218492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.249246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:54:22.218551Z","caller":"traceutil/trace.go:171","msg":"trace[1438113075] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:775; }","duration":"556.409229ms","start":"2026-06-02T23:54:21.662124Z","end":"2026-06-02T23:54:22.218534Z","steps":["trace[1438113075] 'agreement among raft nodes before linearized reading' (duration: 556.202734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:22.218596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:21.662089Z","time spent":"556.49227ms","remote":"127.0.0.1:48834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-06-02T23:54:22.218679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.365283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T23:54:22.218827Z","caller":"traceutil/trace.go:171","msg":"trace[164987529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"619.511347ms","start":"2026-06-02T23:54:21.599251Z","end":"2026-06-02T23:54:22.218762Z","steps":["trace[164987529] 'agreement among raft nodes before linearized reading' (duration: 618.928023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:22.218881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:21.599204Z","time spent":"619.6593ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T23:54:22.219468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.862689ms","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-06-02T23:54:22.219529Z","caller":"traceutil/trace.go:171","msg":"trace[503426119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"530.951321ms","start":"2026-06-02T23:54:21.688564Z","end":"2026-06-02T23:54:22.219515Z","steps":["trace[503426119] 'agreement among raft nodes before linearized reading' (duration: 529.407815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:22.219572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:21.688553Z","time spent":"531.008112ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T23:54:36.524649Z","caller":"traceutil/trace.go:171","msg":"trace[272651782] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"163.550027ms","start":"2026-06-02T23:54:36.361069Z","end":"2026-06-02T23:54:36.524619Z","steps":["trace[272651782] 'read index received' (duration: 163.324362ms)","trace[272651782] 'applied index is now lower than readState.Index' (duration: 224.485µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:54:36.524844Z","caller":"traceutil/trace.go:171","msg":"trace[381431748] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"235.084629ms","start":"2026-06-02T23:54:36.289731Z","end":"2026-06-02T23:54:36.524816Z","steps":["trace[381431748] 'process raft request' (duration: 234.72764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:36.525102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.381961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:54:36.525186Z","caller":"traceutil/trace.go:171","msg":"trace[1128626856] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:906; }","duration":"101.476994ms","start":"2026-06-02T23:54:36.423689Z","end":"2026-06-02T23:54:36.525166Z","steps":["trace[1128626856] 'agreement among raft nodes before linearized reading' (duration: 101.348471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:36.52511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.773259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-02T23:54:36.5254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.983945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T23:54:36.525452Z","caller":"traceutil/trace.go:171","msg":"trace[1335203646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:906; }","duration":"141.072438ms","start":"2026-06-02T23:54:36.384367Z","end":"2026-06-02T23:54:36.52544Z","steps":["trace[1335203646] 'agreement among raft nodes before linearized reading' (duration: 140.914044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:36.525555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.490568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-02T23:54:36.525572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.145346ms","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-06-02T23:54:36.525651Z","caller":"traceutil/trace.go:171","msg":"trace[1456347959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:906; }","duration":"140.253368ms","start":"2026-06-02T23:54:36.385381Z","end":"2026-06-02T23:54:36.525634Z","steps":["trace[1456347959] 'agreement among raft nodes before linearized reading' (duration: 140.003143ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:36.525599Z","caller":"traceutil/trace.go:171","msg":"trace[219687592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"164.534389ms","start":"2026-06-02T23:54:36.361053Z","end":"2026-06-02T23:54:36.525588Z","steps":["trace[219687592] 'agreement among raft nodes before linearized reading' (duration: 164.396066ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:36.52557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.141132ms","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-06-02T23:54:36.525873Z","caller":"traceutil/trace.go:171","msg":"trace[482808065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:906; }","duration":"158.49027ms","start":"2026-06-02T23:54:36.36737Z","end":"2026-06-02T23:54:36.525861Z","steps":["trace[482808065] 'agreement among raft nodes before linearized reading' (duration: 158.05643ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:36.526082Z","caller":"traceutil/trace.go:171","msg":"trace[115162529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:906; }","duration":"163.751492ms","start":"2026-06-02T23:54:36.362281Z","end":"2026-06-02T23:54:36.526032Z","steps":["trace[115162529] 'agreement among raft nodes before linearized reading' (duration: 162.643166ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:36.650793Z","caller":"traceutil/trace.go:171","msg":"trace[1741899066] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"119.896668ms","start":"2026-06-02T23:54:36.530872Z","end":"2026-06-02T23:54:36.650769Z","steps":["trace[1741899066] 'process raft request' (duration: 119.181822ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:36.651146Z","caller":"traceutil/trace.go:171","msg":"trace[1835739915] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"120.179535ms","start":"2026-06-02T23:54:36.530943Z","end":"2026-06-02T23:54:36.651122Z","steps":["trace[1835739915] 'process raft request' (duration: 119.771876ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:36.651449Z","caller":"traceutil/trace.go:171","msg":"trace[278978780] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"120.282767ms","start":"2026-06-02T23:54:36.531143Z","end":"2026-06-02T23:54:36.651425Z","steps":["trace[278978780] 'process raft request' (duration: 119.821537ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:36.651529Z","caller":"traceutil/trace.go:171","msg":"trace[1581179635] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"119.408377ms","start":"2026-06-02T23:54:36.532106Z","end":"2026-06-02T23:54:36.651514Z","steps":["trace[1581179635] 'process raft request' (duration: 119.372626ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:36.651541Z","caller":"traceutil/trace.go:171","msg":"trace[40577462] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"120.269937ms","start":"2026-06-02T23:54:36.531236Z","end":"2026-06-02T23:54:36.651506Z","steps":["trace[40577462] 'process raft request' (duration: 120.071673ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:36.651589Z","caller":"traceutil/trace.go:171","msg":"trace[2000877121] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"120.199745ms","start":"2026-06-02T23:54:36.531357Z","end":"2026-06-02T23:54:36.651557Z","steps":["trace[2000877121] 'process raft request' (duration: 120.026011ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:36.65165Z","caller":"traceutil/trace.go:171","msg":"trace[1678908227] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"119.848057ms","start":"2026-06-02T23:54:36.531792Z","end":"2026-06-02T23:54:36.651641Z","steps":["trace[1678908227] 'process raft request' (duration: 119.646343ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:37.063533Z","caller":"traceutil/trace.go:171","msg":"trace[962218070] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"183.562129ms","start":"2026-06-02T23:54:36.879943Z","end":"2026-06-02T23:54:37.063505Z","steps":["trace[962218070] 'process raft request' (duration: 150.111207ms)","trace[962218070] 'compare' (duration: 33.313839ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:54:37.104531Z","caller":"traceutil/trace.go:171","msg":"trace[1667017104] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"162.83425ms","start":"2026-06-02T23:54:36.941664Z","end":"2026-06-02T23:54:37.104498Z","steps":["trace[1667017104] 'process raft request' (duration: 162.670846ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:37.284137Z","caller":"traceutil/trace.go:171","msg":"trace[1170576389] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"173.384024ms","start":"2026-06-02T23:54:37.110732Z","end":"2026-06-02T23:54:37.284116Z","steps":["trace[1170576389] 'read index received' (duration: 81.649145ms)","trace[1170576389] 'applied index is now lower than readState.Index' (duration: 91.733929ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:54:37.284248Z","caller":"traceutil/trace.go:171","msg":"trace[586843629] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"214.256728ms","start":"2026-06-02T23:54:37.069979Z","end":"2026-06-02T23:54:37.284236Z","steps":["trace[586843629] 'process raft request' (duration: 122.380396ms)","trace[586843629] 'compare' (duration: 91.639576ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:54:37.284394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.65156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:54:37.284475Z","caller":"traceutil/trace.go:171","msg":"trace[438221411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:919; }","duration":"173.771443ms","start":"2026-06-02T23:54:37.110685Z","end":"2026-06-02T23:54:37.284457Z","steps":["trace[438221411] 'agreement among raft nodes before linearized reading' (duration: 173.63769ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:03.145489Z","caller":"traceutil/trace.go:171","msg":"trace[1954700317] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"272.512907ms","start":"2026-06-02T23:55:02.872954Z","end":"2026-06-02T23:55:03.145467Z","steps":["trace[1954700317] 'read index received' (duration: 272.286332ms)","trace[1954700317] 'applied index is now lower than readState.Index' (duration: 225.565µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:03.145541Z","caller":"traceutil/trace.go:171","msg":"trace[915702516] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"277.571173ms","start":"2026-06-02T23:55:02.867954Z","end":"2026-06-02T23:55:03.145525Z","steps":["trace[915702516] 'process raft request' (duration: 277.370218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:03.145752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.842456ms","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-06-02T23:55:03.145772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.995078ms","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-06-02T23:55:03.145785Z","caller":"traceutil/trace.go:171","msg":"trace[1357069660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"151.926398ms","start":"2026-06-02T23:55:02.99385Z","end":"2026-06-02T23:55:03.145776Z","steps":["trace[1357069660] 'agreement among raft nodes before linearized reading' (duration: 151.828606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:03.145845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.90556ms","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-06-02T23:55:03.145869Z","caller":"traceutil/trace.go:171","msg":"trace[1222809065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1006; }","duration":"219.147022ms","start":"2026-06-02T23:55:02.926708Z","end":"2026-06-02T23:55:03.145855Z","steps":["trace[1222809065] 'agreement among raft nodes before linearized reading' (duration: 218.920156ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:03.145883Z","caller":"traceutil/trace.go:171","msg":"trace[1243432283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1006; }","duration":"216.975592ms","start":"2026-06-02T23:55:02.928896Z","end":"2026-06-02T23:55:03.145871Z","steps":["trace[1243432283] 'agreement among raft nodes before linearized reading' (duration: 216.830929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:03.145987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.734492ms","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-06-02T23:55:03.146014Z","caller":"traceutil/trace.go:171","msg":"trace[740454320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1006; }","duration":"273.08693ms","start":"2026-06-02T23:55:02.872919Z","end":"2026-06-02T23:55:03.146005Z","steps":["trace[740454320] 'agreement among raft nodes before linearized reading' (duration: 272.66371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:03.651433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.541503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:03.651503Z","caller":"traceutil/trace.go:171","msg":"trace[977802877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"504.647575ms","start":"2026-06-02T23:55:03.146839Z","end":"2026-06-02T23:55:03.651487Z","steps":["trace[977802877] 'range keys from in-memory index tree' (duration: 504.493541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:03.651538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:03.146826Z","time spent":"504.703526ms","remote":"127.0.0.1:48674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T23:55:03.651562Z","caller":"traceutil/trace.go:171","msg":"trace[1514760474] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"502.445165ms","start":"2026-06-02T23:55:03.149103Z","end":"2026-06-02T23:55:03.651548Z","steps":["trace[1514760474] 'process raft request' (duration: 502.397044ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:03.651612Z","caller":"traceutil/trace.go:171","msg":"trace[870111449] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"502.481956ms","start":"2026-06-02T23:55:03.149103Z","end":"2026-06-02T23:55:03.651585Z","steps":["trace[870111449] 'process raft request' (duration: 424.387253ms)","trace[870111449] 'compare' (duration: 77.845917ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:03.651647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:03.149085Z","time spent":"502.520286ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:03.65168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:03.149085Z","time spent":"502.559097ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:03.763285Z","caller":"traceutil/trace.go:171","msg":"trace[1176420305] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"614.101599ms","start":"2026-06-02T23:55:03.149162Z","end":"2026-06-02T23:55:03.763264Z","steps":["trace[1176420305] 'process raft request' (duration: 613.863973ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:03.763289Z","caller":"traceutil/trace.go:171","msg":"trace[580060311] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"557.849277ms","start":"2026-06-02T23:55:03.205416Z","end":"2026-06-02T23:55:03.763265Z","steps":["trace[580060311] 'process raft request' (duration: 557.786525ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:03.763372Z","caller":"traceutil/trace.go:171","msg":"trace[1382925775] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"612.744978ms","start":"2026-06-02T23:55:03.150592Z","end":"2026-06-02T23:55:03.763337Z","steps":["trace[1382925775] 'process raft request' (duration: 612.571124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:03.763412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:03.149137Z","time spent":"614.199401ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:03.763507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:03.150576Z","time spent":"612.85986ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:03.763488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:03.205393Z","time spent":"557.9927ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:20.299515Z","caller":"traceutil/trace.go:171","msg":"trace[1996544971] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"358.061591ms","start":"2026-06-02T23:55:19.941426Z","end":"2026-06-02T23:55:20.299488Z","steps":["trace[1996544971] 'process raft request' (duration: 357.796485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:20.29974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:19.941411Z","time spent":"358.190384ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:21.27265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.439427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:55:21.272864Z","caller":"traceutil/trace.go:171","msg":"trace[1691744609] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1108; }","duration":"163.534813ms","start":"2026-06-02T23:55:21.109313Z","end":"2026-06-02T23:55:21.272848Z","steps":["trace[1691744609] 'read index received' (duration: 34.68µs)","trace[1691744609] 'applied index is now lower than readState.Index' (duration: 163.499243ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:21.272878Z","caller":"traceutil/trace.go:171","msg":"trace[1846908257] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"1.330624929s","start":"2026-06-02T23:55:19.942217Z","end":"2026-06-02T23:55:21.272841Z","steps":["trace[1846908257] 'process raft request' (duration: 620.888775ms)","trace[1846908257] 'compare' (duration: 709.191161ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:21.273059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.739408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:55:21.273058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:19.942189Z","time spent":"1.330778342s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:21.273064Z","caller":"traceutil/trace.go:171","msg":"trace[1166070554] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"1.245718799s","start":"2026-06-02T23:55:20.027305Z","end":"2026-06-02T23:55:21.273023Z","steps":["trace[1166070554] 'process raft request' (duration: 1.245441623s)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:21.273117Z","caller":"traceutil/trace.go:171","msg":"trace[290980219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"163.82376ms","start":"2026-06-02T23:55:21.109277Z","end":"2026-06-02T23:55:21.2731Z","steps":["trace[290980219] 'agreement among raft nodes before linearized reading' (duration: 163.667016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.273228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:20.02728Z","time spent":"1.245868263s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:21.273107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.633275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:21.273355Z","caller":"traceutil/trace.go:171","msg":"trace[374543606] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1065; }","duration":"105.88414ms","start":"2026-06-02T23:55:21.167458Z","end":"2026-06-02T23:55:21.273342Z","steps":["trace[374543606] 'agreement among raft nodes before linearized reading' (duration: 105.615264ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:21.73236Z","caller":"traceutil/trace.go:171","msg":"trace[744684810] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"149.597685ms","start":"2026-06-02T23:55:21.582736Z","end":"2026-06-02T23:55:21.732334Z","steps":["trace[744684810] 'read index received' (duration: 134.59191ms)","trace[744684810] 'applied index is now lower than readState.Index' (duration: 15.004655ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:21.732381Z","caller":"traceutil/trace.go:171","msg":"trace[572704784] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"455.324194ms","start":"2026-06-02T23:55:21.277025Z","end":"2026-06-02T23:55:21.732349Z","steps":["trace[572704784] 'process raft request' (duration: 440.323049ms)","trace[572704784] 'compare' (duration: 14.836791ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:21.732505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.755869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T23:55:21.732571Z","caller":"traceutil/trace.go:171","msg":"trace[1724633850] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1066; }","duration":"149.882492ms","start":"2026-06-02T23:55:21.582675Z","end":"2026-06-02T23:55:21.732557Z","steps":["trace[1724633850] 'agreement among raft nodes before linearized reading' (duration: 149.760249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.732522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.277001Z","time spent":"455.460707ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:22.364199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291913764652589,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T23:55:22.590635Z","caller":"traceutil/trace.go:171","msg":"trace[1433944623] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"783.930639ms","start":"2026-06-02T23:55:21.806678Z","end":"2026-06-02T23:55:22.590608Z","steps":["trace[1433944623] 'process raft request' (duration: 783.775415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:22.590811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.806652Z","time spent":"784.066161ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:22.621156Z","caller":"traceutil/trace.go:171","msg":"trace[1910287189] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1112; }","duration":"757.566952ms","start":"2026-06-02T23:55:21.863566Z","end":"2026-06-02T23:55:22.621133Z","steps":["trace[1910287189] 'read index received' (duration: 726.96272ms)","trace[1910287189] 'applied index is now lower than readState.Index' (duration: 30.602892ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:22.621284Z","caller":"traceutil/trace.go:171","msg":"trace[125667088] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"679.239884ms","start":"2026-06-02T23:55:21.942034Z","end":"2026-06-02T23:55:22.621273Z","steps":["trace[125667088] 'process raft request' (duration: 678.972658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:22.621391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.942008Z","time spent":"679.305605ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:22.62161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.819507ms","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-06-02T23:55:22.621645Z","caller":"traceutil/trace.go:171","msg":"trace[2016781418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1069; }","duration":"317.884238ms","start":"2026-06-02T23:55:22.303752Z","end":"2026-06-02T23:55:22.621636Z","steps":["trace[2016781418] 'agreement among raft nodes before linearized reading' (duration: 317.765865ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:22.621625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.955781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:55:22.621647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.060614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:55:22.621673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:22.303734Z","time spent":"317.933219ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T23:55:22.621708Z","caller":"traceutil/trace.go:171","msg":"trace[318163627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1069; }","duration":"511.089094ms","start":"2026-06-02T23:55:22.1106Z","end":"2026-06-02T23:55:22.621689Z","steps":["trace[318163627] 'agreement among raft nodes before linearized reading' (duration: 510.93268ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:22.621722Z","caller":"traceutil/trace.go:171","msg":"trace[1286285675] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1069; }","duration":"758.188047ms","start":"2026-06-02T23:55:21.863515Z","end":"2026-06-02T23:55:22.621703Z","steps":["trace[1286285675] 'agreement among raft nodes before linearized reading' (duration: 757.987573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:22.621749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:22.110581Z","time spent":"511.159865ms","remote":"127.0.0.1:48684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T23:55:22.621699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.32421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:55:22.621787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.863497Z","time spent":"758.278019ms","remote":"127.0.0.1:41996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-06-02T23:55:22.621863Z","caller":"traceutil/trace.go:171","msg":"trace[114209950] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1069; }","duration":"606.589096ms","start":"2026-06-02T23:55:22.015247Z","end":"2026-06-02T23:55:22.621836Z","steps":["trace[114209950] 'agreement among raft nodes before linearized reading' (duration: 606.275669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:22.622113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:22.015227Z","time spent":"606.758011ms","remote":"127.0.0.1:41808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-06-02T23:55:24.433277Z","caller":"traceutil/trace.go:171","msg":"trace[337288870] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"350.514718ms","start":"2026-06-02T23:55:24.082726Z","end":"2026-06-02T23:55:24.433241Z","steps":["trace[337288870] 'read index received' (duration: 258.540726ms)","trace[337288870] 'applied index is now lower than readState.Index' (duration: 91.971312ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:24.433303Z","caller":"traceutil/trace.go:171","msg":"trace[1545320360] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"631.695263ms","start":"2026-06-02T23:55:23.801578Z","end":"2026-06-02T23:55:24.433274Z","steps":["trace[1545320360] 'process raft request' (duration: 539.677931ms)","trace[1545320360] 'compare' (duration: 91.733496ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:24.433424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.669101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:24.433528Z","caller":"traceutil/trace.go:171","msg":"trace[1437955987] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1073; }","duration":"350.778424ms","start":"2026-06-02T23:55:24.082721Z","end":"2026-06-02T23:55:24.4335Z","steps":["trace[1437955987] 'agreement among raft nodes before linearized reading' (duration: 350.62457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:24.433531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:23.801559Z","time spent":"631.863937ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:24.433576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:24.082704Z","time spent":"350.860155ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-06-02T23:55:24.47608Z","caller":"traceutil/trace.go:171","msg":"trace[124961130] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"384.978198ms","start":"2026-06-02T23:55:24.091001Z","end":"2026-06-02T23:55:24.475979Z","steps":["trace[124961130] 'process raft request' (duration: 384.686161ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:24.476214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:24.090976Z","time spent":"385.173343ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:24.513391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.579175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:55:24.513468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.051467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:24.513484Z","caller":"traceutil/trace.go:171","msg":"trace[420972174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"403.709199ms","start":"2026-06-02T23:55:24.109756Z","end":"2026-06-02T23:55:24.513465Z","steps":["trace[420972174] 'agreement among raft nodes before linearized reading' (duration: 403.573705ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:24.513529Z","caller":"traceutil/trace.go:171","msg":"trace[922887047] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1074; }","duration":"225.118759ms","start":"2026-06-02T23:55:24.288396Z","end":"2026-06-02T23:55:24.513515Z","steps":["trace[922887047] 'agreement among raft nodes before linearized reading' (duration: 225.018556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:24.513534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:24.10974Z","time spent":"403.78635ms","remote":"127.0.0.1:48674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T23:55:25.409603Z","caller":"traceutil/trace.go:171","msg":"trace[89931371] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"106.033764ms","start":"2026-06-02T23:55:25.303545Z","end":"2026-06-02T23:55:25.409579Z","steps":["trace[89931371] 'process raft request' (duration: 105.904601ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:37.806335Z","caller":"traceutil/trace.go:171","msg":"trace[768666484] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"101.03709ms","start":"2026-06-02T23:55:37.705273Z","end":"2026-06-02T23:55:37.80631Z","steps":["trace[768666484] 'process raft request' (duration: 100.945748ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:37.806426Z","caller":"traceutil/trace.go:171","msg":"trace[932708231] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"103.417304ms","start":"2026-06-02T23:55:37.70295Z","end":"2026-06-02T23:55:37.806367Z","steps":["trace[932708231] 'process raft request' (duration: 78.367099ms)","trace[932708231] 'compare' (duration: 24.786049ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:41.269425Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291913764652847,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T23:55:41.691167Z","caller":"traceutil/trace.go:171","msg":"trace[1144173134] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"981.249789ms","start":"2026-06-02T23:55:40.709889Z","end":"2026-06-02T23:55:41.691138Z","steps":["trace[1144173134] 'process raft request' (duration: 937.115056ms)","trace[1144173134] 'compare' (duration: 43.898668ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:41.691246Z","caller":"traceutil/trace.go:171","msg":"trace[369859412] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"922.04854ms","start":"2026-06-02T23:55:40.769174Z","end":"2026-06-02T23:55:41.691223Z","steps":["trace[369859412] 'read index received' (duration: 877.837515ms)","trace[369859412] 'applied index is now lower than readState.Index' (duration: 44.209725ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:41.691361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:40.70986Z","time spent":"981.429523ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:41.691445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.262225ms","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-06-02T23:55:41.691477Z","caller":"traceutil/trace.go:171","msg":"trace[1560279530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1181; }","duration":"922.352287ms","start":"2026-06-02T23:55:40.769115Z","end":"2026-06-02T23:55:41.691468Z","steps":["trace[1560279530] 'agreement among raft nodes before linearized reading' (duration: 922.219774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:41.691489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.661605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:55:41.69151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:40.769095Z","time spent":"922.405997ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T23:55:41.691538Z","caller":"traceutil/trace.go:171","msg":"trace[937074709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1181; }","duration":"581.742696ms","start":"2026-06-02T23:55:41.109782Z","end":"2026-06-02T23:55:41.691525Z","steps":["trace[937074709] 'agreement among raft nodes before linearized reading' (duration: 581.664034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:41.691568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:41.109758Z","time spent":"581.802858ms","remote":"127.0.0.1:48684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T23:55:41.69125Z","caller":"traceutil/trace.go:171","msg":"trace[415059771] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"132.585644ms","start":"2026-06-02T23:55:41.558639Z","end":"2026-06-02T23:55:41.691225Z","steps":["trace[415059771] 'process raft request' (duration: 132.478862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:41.691774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.313063ms","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-06-02T23:55:41.691801Z","caller":"traceutil/trace.go:171","msg":"trace[1213581560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1181; }","duration":"208.381564ms","start":"2026-06-02T23:55:41.483411Z","end":"2026-06-02T23:55:41.691793Z","steps":["trace[1213581560] 'agreement among raft nodes before linearized reading' (duration: 208.294662ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:42.276669Z","caller":"traceutil/trace.go:171","msg":"trace[1981861127] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1232; }","duration":"331.338138ms","start":"2026-06-02T23:55:41.945176Z","end":"2026-06-02T23:55:42.276514Z","steps":["trace[1981861127] 'read index received' (duration: 328.41092ms)","trace[1981861127] 'applied index is now lower than readState.Index' (duration: 2.925878ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:42.276795Z","caller":"traceutil/trace.go:171","msg":"trace[433435160] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"579.457074ms","start":"2026-06-02T23:55:41.697326Z","end":"2026-06-02T23:55:42.276783Z","steps":["trace[433435160] 'process raft request' (duration: 578.588114ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:42.276776Z","caller":"traceutil/trace.go:171","msg":"trace[758240821] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"579.499585ms","start":"2026-06-02T23:55:41.697231Z","end":"2026-06-02T23:55:42.276731Z","steps":["trace[758240821] 'process raft request' (duration: 576.344522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:42.276893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:41.697306Z","time spent":"579.515855ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:42.276993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:41.697218Z","time spent":"579.661429ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:42.277509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.33088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T23:55:42.277556Z","caller":"traceutil/trace.go:171","msg":"trace[188616761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1183; }","duration":"332.413602ms","start":"2026-06-02T23:55:41.945131Z","end":"2026-06-02T23:55:42.277545Z","steps":["trace[188616761] 'agreement among raft nodes before linearized reading' (duration: 332.265289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:42.277721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:41.945111Z","time spent":"332.589976ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T23:55:42.277926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.308762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:42.277949Z","caller":"traceutil/trace.go:171","msg":"trace[1176644624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1183; }","duration":"167.368222ms","start":"2026-06-02T23:55:42.110574Z","end":"2026-06-02T23:55:42.277942Z","steps":["trace[1176644624] 'agreement among raft nodes before linearized reading' (duration: 167.329922ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:46.418346Z","caller":"traceutil/trace.go:171","msg":"trace[76684046] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"123.008984ms","start":"2026-06-02T23:55:46.295311Z","end":"2026-06-02T23:55:46.41832Z","steps":["trace[76684046] 'process raft request' (duration: 122.868211ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:46.431653Z","caller":"traceutil/trace.go:171","msg":"trace[217136242] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"134.838725ms","start":"2026-06-02T23:55:46.296792Z","end":"2026-06-02T23:55:46.431631Z","steps":["trace[217136242] 'process raft request' (duration: 134.692572ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:46.431634Z","caller":"traceutil/trace.go:171","msg":"trace[577182917] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1246; }","duration":"126.771361ms","start":"2026-06-02T23:55:46.304826Z","end":"2026-06-02T23:55:46.431597Z","steps":["trace[577182917] 'read index received' (duration: 113.360223ms)","trace[577182917] 'applied index is now lower than readState.Index' (duration: 13.410598ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:46.431788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.947314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T23:55:46.431825Z","caller":"traceutil/trace.go:171","msg":"trace[333514700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1196; }","duration":"127.016097ms","start":"2026-06-02T23:55:46.3048Z","end":"2026-06-02T23:55:46.431816Z","steps":["trace[333514700] 'agreement among raft nodes before linearized reading' (duration: 126.894894ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:58.050939Z","caller":"traceutil/trace.go:171","msg":"trace[185507957] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"234.935174ms","start":"2026-06-02T23:55:57.815969Z","end":"2026-06-02T23:55:58.050904Z","steps":["trace[185507957] 'process raft request' (duration: 234.707039ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:59.076107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.056466ms","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-06-02T23:55:59.076184Z","caller":"traceutil/trace.go:171","msg":"trace[192050579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1253; }","duration":"603.146158ms","start":"2026-06-02T23:55:58.47302Z","end":"2026-06-02T23:55:59.076166Z","steps":["trace[192050579] 'range keys from in-memory index tree' (duration: 602.790929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:59.076224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:58.472976Z","time spent":"603.23835ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T23:55:59.076462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.228192ms","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-06-02T23:55:59.076721Z","caller":"traceutil/trace.go:171","msg":"trace[1114964077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1253; }","duration":"462.525919ms","start":"2026-06-02T23:55:58.614179Z","end":"2026-06-02T23:55:59.076705Z","steps":["trace[1114964077] 'range keys from in-memory index tree' (duration: 462.076799ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:59.076752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.5322ms","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-06-02T23:55:59.076787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:58.614154Z","time spent":"462.615981ms","remote":"127.0.0.1:48974","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":"info","ts":"2026-06-02T23:55:59.076803Z","caller":"traceutil/trace.go:171","msg":"trace[931915776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1253; }","duration":"567.639552ms","start":"2026-06-02T23:55:58.509152Z","end":"2026-06-02T23:55:59.076791Z","steps":["trace[931915776] 'range keys from in-memory index tree' (duration: 567.166792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:59.07683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:58.509129Z","time spent":"567.696234ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T23:55:59.076842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.205778ms","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-06-02T23:55:59.076908Z","caller":"traceutil/trace.go:171","msg":"trace[648498556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1253; }","duration":"596.302081ms","start":"2026-06-02T23:55:58.480589Z","end":"2026-06-02T23:55:59.076891Z","steps":["trace[648498556] 'range keys from in-memory index tree' (duration: 596.056524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:59.076947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:58.480578Z","time spent":"596.358062ms","remote":"127.0.0.1:48974","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-06-02T23:55:59.076974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.248788ms","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-06-02T23:55:59.077012Z","caller":"traceutil/trace.go:171","msg":"trace[356500463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1253; }","duration":"251.32952ms","start":"2026-06-02T23:55:58.825671Z","end":"2026-06-02T23:55:59.077001Z","steps":["trace[356500463] 'range keys from in-memory index tree' (duration: 251.115876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:59.077063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.708596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-02T23:55:59.077108Z","caller":"traceutil/trace.go:171","msg":"trace[1839651918] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:1253; }","duration":"152.812148ms","start":"2026-06-02T23:55:58.924283Z","end":"2026-06-02T23:55:59.077095Z","steps":["trace[1839651918] 'range keys from in-memory index tree' (duration: 152.595244ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:00.37917Z","caller":"traceutil/trace.go:171","msg":"trace[1789848674] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"566.296921ms","start":"2026-06-02T23:55:59.812841Z","end":"2026-06-02T23:56:00.379138Z","steps":["trace[1789848674] 'process raft request' (duration: 566.014995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:00.379336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:59.812824Z","time spent":"566.428925ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:56:01.435532Z","caller":"traceutil/trace.go:171","msg":"trace[330323602] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"264.732638ms","start":"2026-06-02T23:56:01.170764Z","end":"2026-06-02T23:56:01.435496Z","steps":["trace[330323602] 'process raft request' (duration: 264.583885ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:01.618538Z","caller":"traceutil/trace.go:171","msg":"trace[247988268] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1316; }","duration":"119.37825ms","start":"2026-06-02T23:56:01.499135Z","end":"2026-06-02T23:56:01.618513Z","steps":["trace[247988268] 'read index received' (duration: 102.302788ms)","trace[247988268] 'applied index is now lower than readState.Index' (duration: 17.074502ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:56:01.618599Z","caller":"traceutil/trace.go:171","msg":"trace[1357694912] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"445.000657ms","start":"2026-06-02T23:56:01.173589Z","end":"2026-06-02T23:56:01.61859Z","steps":["trace[1357694912] 'process raft request' (duration: 444.883874ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:01.618646Z","caller":"traceutil/trace.go:171","msg":"trace[1481282299] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"446.86399ms","start":"2026-06-02T23:56:01.171773Z","end":"2026-06-02T23:56:01.618637Z","steps":["trace[1481282299] 'process raft request' (duration: 446.556493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:01.618673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.516944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T23:56:01.618728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:01.173581Z","time spent":"445.062028ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:56:01.618727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:01.171754Z","time spent":"446.913131ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:56:01.618732Z","caller":"traceutil/trace.go:171","msg":"trace[458789488] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1265; }","duration":"119.633147ms","start":"2026-06-02T23:56:01.499088Z","end":"2026-06-02T23:56:01.618721Z","steps":["trace[458789488] 'agreement among raft nodes before linearized reading' (duration: 119.526645ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:01.618592Z","caller":"traceutil/trace.go:171","msg":"trace[1463106226] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"445.158141ms","start":"2026-06-02T23:56:01.17342Z","end":"2026-06-02T23:56:01.618579Z","steps":["trace[1463106226] 'process raft request' (duration: 445.002167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:01.618922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:01.173408Z","time spent":"445.404076ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:56:01.618477Z","caller":"traceutil/trace.go:171","msg":"trace[1024386786] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"447.382072ms","start":"2026-06-02T23:56:01.171071Z","end":"2026-06-02T23:56:01.618453Z","steps":["trace[1024386786] 'process raft request' (duration: 430.339111ms)","trace[1024386786] 'compare' (duration: 16.645332ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:56:01.619572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:01.171062Z","time spent":"448.473857ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:56:02.49184Z","caller":"traceutil/trace.go:171","msg":"trace[474512811] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"106.886394ms","start":"2026-06-02T23:56:02.384635Z","end":"2026-06-02T23:56:02.491521Z","steps":["trace[474512811] 'process raft request' (duration: 106.703789ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:02.774414Z","caller":"traceutil/trace.go:171","msg":"trace[970869834] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"389.082503ms","start":"2026-06-02T23:56:02.3853Z","end":"2026-06-02T23:56:02.774382Z","steps":["trace[970869834] 'process raft request' (duration: 334.160012ms)","trace[970869834] 'compare' (duration: 54.377648ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:56:02.774836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:02.385278Z","time spent":"389.220687ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:56:11.922324Z","caller":"traceutil/trace.go:171","msg":"trace[972895158] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"436.726407ms","start":"2026-06-02T23:56:11.485523Z","end":"2026-06-02T23:56:11.92225Z","steps":["trace[972895158] 'read index received' (duration: 436.415709ms)","trace[972895158] 'applied index is now lower than readState.Index' (duration: 309.638µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:56:11.922508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.961832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T23:56:11.922558Z","caller":"traceutil/trace.go:171","msg":"trace[1293402482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1338; }","duration":"437.045663ms","start":"2026-06-02T23:56:11.4855Z","end":"2026-06-02T23:56:11.922546Z","steps":["trace[1293402482] 'agreement among raft nodes before linearized reading' (duration: 436.867149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:11.922611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:11.485489Z","time spent":"437.108266ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T23:56:11.922346Z","caller":"traceutil/trace.go:171","msg":"trace[1565950451] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"464.896153ms","start":"2026-06-02T23:56:11.457409Z","end":"2026-06-02T23:56:11.922306Z","steps":["trace[1565950451] 'process raft request' (duration: 464.637807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:11.922743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:11.457393Z","time spent":"465.263762ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:56:11.922886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.681843ms","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-06-02T23:56:11.922918Z","caller":"traceutil/trace.go:171","msg":"trace[1937977523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1338; }","duration":"256.768655ms","start":"2026-06-02T23:56:11.666142Z","end":"2026-06-02T23:56:11.92291Z","steps":["trace[1937977523] 'agreement among raft nodes before linearized reading' (duration: 256.695953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:11.922901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.907191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T23:56:11.923101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.121332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:56:11.924196Z","caller":"traceutil/trace.go:171","msg":"trace[1438369642] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1338; }","duration":"102.140265ms","start":"2026-06-02T23:56:11.821919Z","end":"2026-06-02T23:56:11.924059Z","steps":["trace[1438369642] 'agreement among raft nodes before linearized reading' (duration: 101.131132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:11.92317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.251165ms","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-06-02T23:56:11.924435Z","caller":"traceutil/trace.go:171","msg":"trace[1860208525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1338; }","duration":"261.548305ms","start":"2026-06-02T23:56:11.662873Z","end":"2026-06-02T23:56:11.924421Z","steps":["trace[1860208525] 'agreement among raft nodes before linearized reading' (duration: 260.240264ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:11.923235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.81481ms","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-06-02T23:56:11.924558Z","caller":"traceutil/trace.go:171","msg":"trace[3543154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1338; }","duration":"266.165871ms","start":"2026-06-02T23:56:11.658373Z","end":"2026-06-02T23:56:11.924539Z","steps":["trace[3543154] 'agreement among raft nodes before linearized reading' (duration: 264.772259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:11.923259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.480852ms","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-06-02T23:56:11.924622Z","caller":"traceutil/trace.go:171","msg":"trace[1321421809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1338; }","duration":"258.877253ms","start":"2026-06-02T23:56:11.665737Z","end":"2026-06-02T23:56:11.924614Z","steps":["trace[1321421809] 'agreement among raft nodes before linearized reading' (duration: 257.43702ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:11.926569Z","caller":"traceutil/trace.go:171","msg":"trace[435086795] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1338; }","duration":"340.14624ms","start":"2026-06-02T23:56:11.583808Z","end":"2026-06-02T23:56:11.923954Z","steps":["trace[435086795] 'agreement among raft nodes before linearized reading' (duration: 338.895831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:11.926699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:11.583786Z","time spent":"342.851322ms","remote":"127.0.0.1:48784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-06-02T23:56:12.526281Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291913764653185,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T23:56:13.027432Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291913764653185,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T23:56:13.085442Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.15727695s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-02T23:56:13.412365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.478746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:56:13.412533Z","caller":"traceutil/trace.go:171","msg":"trace[1080484468] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"1.484533694s","start":"2026-06-02T23:56:11.927971Z","end":"2026-06-02T23:56:13.412505Z","steps":["trace[1080484468] 'process raft request' (duration: 1.157675959s)","trace[1080484468] 'compare' (duration: 326.140448ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:56:13.412609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:11.927953Z","time spent":"1.484620265s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:56:13.523731Z","caller":"traceutil/trace.go:171","msg":"trace[1899840440] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"1.592953053s","start":"2026-06-02T23:56:11.930687Z","end":"2026-06-02T23:56:13.52364Z","steps":["trace[1899840440] 'process raft request' (duration: 1.59281815s)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:13.523759Z","caller":"traceutil/trace.go:171","msg":"trace[545774227] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"1.592984553s","start":"2026-06-02T23:56:11.930749Z","end":"2026-06-02T23:56:13.523734Z","steps":["trace[545774227] 'process raft request' (duration: 1.592789949s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:13.523949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:11.930679Z","time spent":"1.593155428s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:56:13.523973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:11.930745Z","time spent":"1.593114576s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:56:13.524431Z","caller":"traceutil/trace.go:171","msg":"trace[295992807] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1395; }","duration":"1.49872469s","start":"2026-06-02T23:56:12.025671Z","end":"2026-06-02T23:56:13.524395Z","steps":["trace[295992807] 'read index received' (duration: 1.060059608s)","trace[295992807] 'applied index is now lower than readState.Index' (duration: 438.663561ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:56:13.524666Z","caller":"traceutil/trace.go:171","msg":"trace[1319241513] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"794.074161ms","start":"2026-06-02T23:56:12.73057Z","end":"2026-06-02T23:56:13.524645Z","steps":["trace[1319241513] 'process raft request' (duration: 793.006607ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:13.524685Z","caller":"traceutil/trace.go:171","msg":"trace[1842924380] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"713.210316ms","start":"2026-06-02T23:56:12.811454Z","end":"2026-06-02T23:56:13.524665Z","steps":["trace[1842924380] 'process raft request' (duration: 712.806876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:13.524752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:12.730553Z","time spent":"794.160664ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:56:13.524777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:12.811429Z","time spent":"713.299607ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:56:13.52499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.499248421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T23:56:13.525055Z","caller":"traceutil/trace.go:171","msg":"trace[227379245] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1345; }","duration":"1.499422335s","start":"2026-06-02T23:56:12.025618Z","end":"2026-06-02T23:56:13.52504Z","steps":["trace[227379245] 'agreement among raft nodes before linearized reading' (duration: 1.499084848s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:13.525102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:12.025592Z","time spent":"1.499498647s","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":140,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-06-02T23:56:13.525006Z","caller":"traceutil/trace.go:171","msg":"trace[351647351] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"1.595091872s","start":"2026-06-02T23:56:11.929884Z","end":"2026-06-02T23:56:13.524976Z","steps":["trace[351647351] 'process raft request' (duration: 1.593385293s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:13.525179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:11.929847Z","time spent":"1.595297407s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:56:13.525133Z","caller":"traceutil/trace.go:171","msg":"trace[844972883] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"1.594214542s","start":"2026-06-02T23:56:11.929987Z","end":"2026-06-02T23:56:13.524201Z","steps":["trace[844972883] 'process raft request' (duration: 1.593467695s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:13.525291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:11.92998Z","time spent":"1.595247776s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:56:13.525505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.37319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-02T23:56:13.525551Z","caller":"traceutil/trace.go:171","msg":"trace[1610310476] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1345; }","duration":"565.471294ms","start":"2026-06-02T23:56:12.960068Z","end":"2026-06-02T23:56:13.52554Z","steps":["trace[1610310476] 'agreement among raft nodes before linearized reading' (duration: 565.337631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:13.525578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:12.96004Z","time spent":"565.531914ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-02T23:56:13.525842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180537884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-02T23:56:13.525883Z","caller":"traceutil/trace.go:171","msg":"trace[872627863] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1345; }","duration":"1.180616486s","start":"2026-06-02T23:56:12.345256Z","end":"2026-06-02T23:56:13.525872Z","steps":["trace[872627863] 'agreement among raft nodes before linearized reading' (duration: 1.180522174s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:13.52591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:12.345241Z","time spent":"1.180662137s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-02T23:56:13.525533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475727021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T23:56:13.526069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.417051044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:56:13.526894Z","caller":"traceutil/trace.go:171","msg":"trace[998558627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1345; }","duration":"1.417899913s","start":"2026-06-02T23:56:12.108985Z","end":"2026-06-02T23:56:13.526885Z","steps":["trace[998558627] 'agreement among raft nodes before linearized reading' (duration: 1.417019273s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:13.526921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:12.108969Z","time spent":"1.417944324s","remote":"127.0.0.1:48684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T23:56:13.526759Z","caller":"traceutil/trace.go:171","msg":"trace[1504860846] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1345; }","duration":"1.477027871s","start":"2026-06-02T23:56:12.049709Z","end":"2026-06-02T23:56:13.526737Z","steps":["trace[1504860846] 'agreement among raft nodes before linearized reading' (duration: 1.47567725s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:13.527874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:56:12.049695Z","time spent":"1.477519062s","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-02T23:56:29.808269Z","caller":"traceutil/trace.go:171","msg":"trace[921525580] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"155.548141ms","start":"2026-06-02T23:56:29.652668Z","end":"2026-06-02T23:56:29.808216Z","steps":["trace[921525580] 'process raft request' (duration: 155.464509ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:29.808765Z","caller":"traceutil/trace.go:171","msg":"trace[1416639919] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"156.146515ms","start":"2026-06-02T23:56:29.652595Z","end":"2026-06-02T23:56:29.808741Z","steps":["trace[1416639919] 'process raft request' (duration: 155.244604ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:57:48.887473Z","caller":"traceutil/trace.go:171","msg":"trace[419104975] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"104.222041ms","start":"2026-06-02T23:57:48.783199Z","end":"2026-06-02T23:57:48.887421Z","steps":["trace[419104975] 'process raft request' (duration: 104.095429ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:59:59.009251Z","caller":"traceutil/trace.go:171","msg":"trace[776562360] linearizableReadLoop","detail":"{readStateIndex:2505; appliedIndex:2504; }","duration":"224.991063ms","start":"2026-06-02T23:59:58.784205Z","end":"2026-06-02T23:59:59.009196Z","steps":["trace[776562360] 'read index received' (duration: 224.799439ms)","trace[776562360] 'applied index is now lower than readState.Index' (duration: 190.094µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:59:59.009289Z","caller":"traceutil/trace.go:171","msg":"trace[1080078673] transaction","detail":"{read_only:false; response_revision:2402; number_of_response:1; }","duration":"314.622164ms","start":"2026-06-02T23:59:58.694633Z","end":"2026-06-02T23:59:59.009255Z","steps":["trace[1080078673] 'process raft request' (duration: 314.399118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:59:59.009466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.223348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-06-02T23:59:59.009469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:59:58.694594Z","time spent":"314.773847ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:59:59.009526Z","caller":"traceutil/trace.go:171","msg":"trace[859959472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2402; }","duration":"225.349962ms","start":"2026-06-02T23:59:58.784166Z","end":"2026-06-02T23:59:59.009516Z","steps":["trace[859959472] 'agreement among raft nodes before linearized reading' (duration: 225.178938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:59:59.009614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.420685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-02T23:59:59.009674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.685628ms","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-06-02T23:59:59.009692Z","caller":"traceutil/trace.go:171","msg":"trace[1627670201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2402; }","duration":"112.549077ms","start":"2026-06-02T23:59:58.897126Z","end":"2026-06-02T23:59:59.009675Z","steps":["trace[1627670201] 'agreement among raft nodes before linearized reading' (duration: 112.311602ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:59:59.009744Z","caller":"traceutil/trace.go:171","msg":"trace[1155123569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2402; }","duration":"111.77634ms","start":"2026-06-02T23:59:58.897947Z","end":"2026-06-02T23:59:59.009724Z","steps":["trace[1155123569] 'agreement among raft nodes before linearized reading' (duration: 111.567875ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:00:20.536382Z","caller":"traceutil/trace.go:171","msg":"trace[436303075] linearizableReadLoop","detail":"{readStateIndex:2618; appliedIndex:2617; }","duration":"128.029749ms","start":"2026-06-03T00:00:20.408334Z","end":"2026-06-03T00:00:20.536363Z","steps":["trace[436303075] 'read index received' (duration: 127.779583ms)","trace[436303075] 'applied index is now lower than readState.Index' (duration: 247.506µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T00:00:20.536476Z","caller":"traceutil/trace.go:171","msg":"trace[716819033] transaction","detail":"{read_only:false; response_revision:2509; number_of_response:1; }","duration":"132.147915ms","start":"2026-06-03T00:00:20.404306Z","end":"2026-06-03T00:00:20.536454Z","steps":["trace[716819033] 'process raft request' (duration: 131.810127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:00:20.536562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.197373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T00:00:20.537147Z","caller":"traceutil/trace.go:171","msg":"trace[1174789757] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:2509; }","duration":"128.820667ms","start":"2026-06-03T00:00:20.408304Z","end":"2026-06-03T00:00:20.537125Z","steps":["trace[1174789757] 'agreement among raft nodes before linearized reading' (duration: 128.187342ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:00:20.669832Z","caller":"traceutil/trace.go:171","msg":"trace[1405056512] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"115.399114ms","start":"2026-06-03T00:00:20.554405Z","end":"2026-06-03T00:00:20.669804Z","steps":["trace[1405056512] 'process raft request' (duration: 87.220243ms)","trace[1405056512] 'compare' (duration: 27.988666ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T00:00:22.872954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.57016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T00:00:22.87306Z","caller":"traceutil/trace.go:171","msg":"trace[4798216] linearizableReadLoop","detail":"{readStateIndex:2629; appliedIndex:2628; }","duration":"175.475431ms","start":"2026-06-03T00:00:22.697568Z","end":"2026-06-03T00:00:22.873043Z","steps":["trace[4798216] 'read index received' (duration: 67.774818ms)","trace[4798216] 'applied index is now lower than readState.Index' (duration: 107.699363ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T00:00:22.873149Z","caller":"traceutil/trace.go:171","msg":"trace[1597252129] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"177.200032ms","start":"2026-06-03T00:00:22.695937Z","end":"2026-06-03T00:00:22.873137Z","steps":["trace[1597252129] 'process raft request' (duration: 69.367206ms)","trace[1597252129] 'compare' (duration: 107.456507ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T00:00:22.873363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.064887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:11246"} {"level":"info","ts":"2026-06-03T00:00:22.873438Z","caller":"traceutil/trace.go:171","msg":"trace[451129698] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:2520; }","duration":"159.163319ms","start":"2026-06-03T00:00:22.714258Z","end":"2026-06-03T00:00:22.873422Z","steps":["trace[451129698] 'agreement among raft nodes before linearized reading' (duration: 158.943504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:00:22.874063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.479494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:117 size:90550"} {"level":"info","ts":"2026-06-03T00:00:22.874119Z","caller":"traceutil/trace.go:171","msg":"trace[246812611] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:117; response_revision:2520; }","duration":"176.551347ms","start":"2026-06-03T00:00:22.697553Z","end":"2026-06-03T00:00:22.874104Z","steps":["trace[246812611] 'agreement among raft nodes before linearized reading' (duration: 175.873131ms)"],"step_count":1}