{"level":"warn","ts":"2026-07-02T05:08:06.072033Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:08:06.072222Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-07-02T05:08:06.136292Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:08:06.136379Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-07-02T05:08:06.136446Z","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-07-02T05:08:06.138033Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-07-02T05:08:06.153797Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-07-02T05:08:07.498964Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.264089028s"} {"level":"info","ts":"2026-07-02T05:08:07.516095Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-07-02T05:08:07.516207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T05:08:07.516249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-07-02T05:08:07.516263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T05:08:07.51628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-07-02T05:08:07.516348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-07-02T05:08:07.532768Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T05:08:07.534749Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T05:08:07.536014Z","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-07-02T05:08:07.539089Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T05:08:07.539569Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T05:08:07.539638Z","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-07-02T05:08:07.539821Z","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-07-02T05:08:07.539842Z","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-07-02T05:08:07.542387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-07-02T05:08:07.54317Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-07-02T05:08:07.544633Z","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-07-02T05:08:07.544715Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-07-02T05:08:07.544791Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-07-02T05:08:07.545103Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T05:08:07.545189Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T05:08:08.117285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T05:08:08.117359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T05:08:08.117407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-07-02T05:08:08.11743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-07-02T05:08:08.117442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-07-02T05:08:08.117459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-07-02T05:08:08.117473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-07-02T05:08:08.119003Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T05:08:08.119072Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:08:08.119092Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:08:08.119221Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:08:08.119598Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T05:08:08.119626Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T05:08:08.120484Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:08:08.120702Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:08:08.120791Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:08:08.122999Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T05:08:08.124686Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-07-02T05:08:32.78692Z","caller":"traceutil/trace.go:171","msg":"trace[225637783] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"135.240188ms","start":"2026-07-02T05:08:32.651634Z","end":"2026-07-02T05:08:32.786874Z","steps":["trace[225637783] 'process raft request' (duration: 134.975101ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:08:46.469573Z","caller":"traceutil/trace.go:171","msg":"trace[1064466204] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"290.940663ms","start":"2026-07-02T05:08:46.178604Z","end":"2026-07-02T05:08:46.469545Z","steps":["trace[1064466204] 'read index received' (duration: 290.759619ms)","trace[1064466204] 'applied index is now lower than readState.Index' (duration: 180.334µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:08:46.469683Z","caller":"traceutil/trace.go:171","msg":"trace[1062752047] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"597.910665ms","start":"2026-07-02T05:08:45.871763Z","end":"2026-07-02T05:08:46.469674Z","steps":["trace[1062752047] 'process raft request' (duration: 597.656429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:46.470017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.205765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:08:46.470187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.335741ms","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-07-02T05:08:46.470424Z","caller":"traceutil/trace.go:171","msg":"trace[1192896659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"249.613168ms","start":"2026-07-02T05:08:46.220776Z","end":"2026-07-02T05:08:46.470389Z","steps":["trace[1192896659] 'agreement among raft nodes before linearized reading' (duration: 249.233949ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:08:46.47055Z","caller":"traceutil/trace.go:171","msg":"trace[820828794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"267.574465ms","start":"2026-07-02T05:08:46.202622Z","end":"2026-07-02T05:08:46.470197Z","steps":["trace[820828794] 'agreement among raft nodes before linearized reading' (duration: 267.105663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:46.470229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:45.871731Z","time spent":"597.979177ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:08:46.471529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.88121ms","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-07-02T05:08:46.471736Z","caller":"traceutil/trace.go:171","msg":"trace[1138989734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"293.149217ms","start":"2026-07-02T05:08:46.178553Z","end":"2026-07-02T05:08:46.471702Z","steps":["trace[1138989734] 'agreement among raft nodes before linearized reading' (duration: 291.463955ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:08:51.775779Z","caller":"traceutil/trace.go:171","msg":"trace[48381621] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"149.836692ms","start":"2026-07-02T05:08:51.625911Z","end":"2026-07-02T05:08:51.775747Z","steps":["trace[48381621] 'process raft request' (duration: 97.682805ms)","trace[48381621] 'compare' (duration: 51.998874ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:08:52.815699Z","caller":"traceutil/trace.go:171","msg":"trace[1821629530] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"300.893835ms","start":"2026-07-02T05:08:52.51475Z","end":"2026-07-02T05:08:52.815644Z","steps":["trace[1821629530] 'process raft request' (duration: 300.632159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:52.816139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:52.514738Z","time spent":"301.153832ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:08:52.952296Z","caller":"traceutil/trace.go:171","msg":"trace[71761252] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"434.927053ms","start":"2026-07-02T05:08:52.517317Z","end":"2026-07-02T05:08:52.952244Z","steps":["trace[71761252] 'process raft request' (duration: 425.299639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:52.952542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:52.517287Z","time spent":"435.122758ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:08:52.953046Z","caller":"traceutil/trace.go:171","msg":"trace[2145005695] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"416.146736ms","start":"2026-07-02T05:08:52.536838Z","end":"2026-07-02T05:08:52.952985Z","steps":["trace[2145005695] 'read index received' (duration: 278.624183ms)","trace[2145005695] 'applied index is now lower than readState.Index' (duration: 137.521203ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:08:52.953101Z","caller":"traceutil/trace.go:171","msg":"trace[191829105] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"229.745955ms","start":"2026-07-02T05:08:52.723337Z","end":"2026-07-02T05:08:52.953082Z","steps":["trace[191829105] 'process raft request' (duration: 228.68715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:52.953322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.493345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T05:08:52.953374Z","caller":"traceutil/trace.go:171","msg":"trace[1182436265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"416.557237ms","start":"2026-07-02T05:08:52.5368Z","end":"2026-07-02T05:08:52.953358Z","steps":["trace[1182436265] 'agreement among raft nodes before linearized reading' (duration: 416.347942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:52.953416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:52.536703Z","time spent":"416.700721ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T05:08:53.222505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.972097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:08:53.222645Z","caller":"traceutil/trace.go:171","msg":"trace[1415314435] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"204.131503ms","start":"2026-07-02T05:08:53.01849Z","end":"2026-07-02T05:08:53.222622Z","steps":["trace[1415314435] 'process raft request' (duration: 46.767838ms)","trace[1415314435] 'compare' (duration: 156.802562ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:08:53.284574Z","caller":"traceutil/trace.go:171","msg":"trace[1766836241] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"263.908196ms","start":"2026-07-02T05:08:53.020646Z","end":"2026-07-02T05:08:53.284554Z","steps":["trace[1766836241] 'process raft request' (duration: 263.706261ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:08:53.285091Z","caller":"traceutil/trace.go:171","msg":"trace[302708366] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"251.708379ms","start":"2026-07-02T05:08:53.033333Z","end":"2026-07-02T05:08:53.285041Z","steps":["trace[302708366] 'process raft request' (duration: 251.149326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:54.013456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.582488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:08:54.013551Z","caller":"traceutil/trace.go:171","msg":"trace[202357382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"423.731091ms","start":"2026-07-02T05:08:53.589798Z","end":"2026-07-02T05:08:54.013529Z","steps":["trace[202357382] 'range keys from in-memory index tree' (duration: 423.453934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:54.013602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:53.589775Z","time spent":"423.815143ms","remote":"127.0.0.1:42278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:08:54.094953Z","caller":"traceutil/trace.go:171","msg":"trace[994699697] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"371.454121ms","start":"2026-07-02T05:08:53.723465Z","end":"2026-07-02T05:08:54.094919Z","steps":["trace[994699697] 'process raft request' (duration: 370.612901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:54.095148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:53.723429Z","time spent":"371.618775ms","remote":"127.0.0.1:42390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:08:54.498597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.856908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:08:54.49891Z","caller":"traceutil/trace.go:171","msg":"trace[1145810478] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"339.639817ms","start":"2026-07-02T05:08:54.159247Z","end":"2026-07-02T05:08:54.498887Z","steps":["trace[1145810478] 'process raft request' (duration: 339.489653ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:08:54.498868Z","caller":"traceutil/trace.go:171","msg":"trace[1746422649] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"488.839734ms","start":"2026-07-02T05:08:54.00992Z","end":"2026-07-02T05:08:54.498759Z","steps":["trace[1746422649] 'process raft request' (duration: 237.708409ms)","trace[1746422649] 'compare' (duration: 250.579502ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:08:54.499013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:54.159207Z","time spent":"339.75128ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:08:54.499179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.966075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:08:54.499295Z","caller":"traceutil/trace.go:171","msg":"trace[2104993537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"484.132979ms","start":"2026-07-02T05:08:54.015134Z","end":"2026-07-02T05:08:54.499267Z","steps":["trace[2104993537] 'agreement among raft nodes before linearized reading' (duration: 483.935364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:54.499338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:54.015109Z","time spent":"484.222302ms","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:08:54.499405Z","caller":"traceutil/trace.go:171","msg":"trace[928640920] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:661; }","duration":"483.715539ms","start":"2026-07-02T05:08:54.01519Z","end":"2026-07-02T05:08:54.498906Z","steps":["trace[928640920] 'read index received' (duration: 78.718694ms)","trace[928640920] 'applied index is now lower than readState.Index' (duration: 404.984635ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:08:54.499729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:54.009883Z","time spent":"489.767157ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:08:55.457776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053750646759760,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:08:55.896191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073289092s","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-07-02T05:08:55.89627Z","caller":"traceutil/trace.go:171","msg":"trace[777334352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.073439817s","start":"2026-07-02T05:08:54.822815Z","end":"2026-07-02T05:08:55.896255Z","steps":["trace[777334352] 'range keys from in-memory index tree' (duration: 1.073098878s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:55.896306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:54.822787Z","time spent":"1.073511258s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T05:08:55.896671Z","caller":"traceutil/trace.go:171","msg":"trace[1366845421] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"939.242594ms","start":"2026-07-02T05:08:54.957393Z","end":"2026-07-02T05:08:55.896635Z","steps":["trace[1366845421] 'read index received' (duration: 854.095014ms)","trace[1366845421] 'applied index is now lower than readState.Index' (duration: 85.14609ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:08:55.897244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.859968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:08:55.897293Z","caller":"traceutil/trace.go:171","msg":"trace[1873521786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"939.965781ms","start":"2026-07-02T05:08:54.957312Z","end":"2026-07-02T05:08:55.897278Z","steps":["trace[1873521786] 'agreement among raft nodes before linearized reading' (duration: 939.843838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:55.897317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:54.957278Z","time spent":"940.034153ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T05:08:55.897452Z","caller":"traceutil/trace.go:171","msg":"trace[10332135] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"991.059364ms","start":"2026-07-02T05:08:54.906339Z","end":"2026-07-02T05:08:55.897398Z","steps":["trace[10332135] 'process raft request' (duration: 905.639367ms)","trace[10332135] 'compare' (duration: 84.089844ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:08:55.897644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.012121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:08:55.897739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:54.906253Z","time spent":"991.358271ms","remote":"127.0.0.1:42390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:08:55.897752Z","caller":"traceutil/trace.go:171","msg":"trace[379591032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"306.183194ms","start":"2026-07-02T05:08:55.591546Z","end":"2026-07-02T05:08:55.897729Z","steps":["trace[379591032] 'agreement among raft nodes before linearized reading' (duration: 305.970739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:55.897817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:55.591523Z","time spent":"306.284187ms","remote":"127.0.0.1:42278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:08:55.897882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.153705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T05:08:55.897975Z","caller":"traceutil/trace.go:171","msg":"trace[819533898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"877.327328ms","start":"2026-07-02T05:08:55.020627Z","end":"2026-07-02T05:08:55.897954Z","steps":["trace[819533898] 'agreement among raft nodes before linearized reading' (duration: 876.818786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:55.89805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:55.02059Z","time spent":"877.447651ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T05:08:55.898664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.666493ms","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-07-02T05:08:55.898785Z","caller":"traceutil/trace.go:171","msg":"trace[771249821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"607.843128ms","start":"2026-07-02T05:08:55.290914Z","end":"2026-07-02T05:08:55.898758Z","steps":["trace[771249821] 'agreement among raft nodes before linearized reading' (duration: 606.671459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:55.898836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:55.29089Z","time spent":"607.93628ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T05:08:57.534022Z","caller":"traceutil/trace.go:171","msg":"trace[161357103] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"384.090297ms","start":"2026-07-02T05:08:57.149898Z","end":"2026-07-02T05:08:57.533988Z","steps":["trace[161357103] 'read index received' (duration: 384.005335ms)","trace[161357103] 'applied index is now lower than readState.Index' (duration: 83.312µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:08:57.53417Z","caller":"traceutil/trace.go:171","msg":"trace[2145401213] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"384.652432ms","start":"2026-07-02T05:08:57.149506Z","end":"2026-07-02T05:08:57.534158Z","steps":["trace[2145401213] 'process raft request' (duration: 384.285184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:57.534365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:57.149481Z","time spent":"384.728764ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:08:57.534464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.439766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:08:57.534601Z","caller":"traceutil/trace.go:171","msg":"trace[1727250964] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:665; }","duration":"384.710082ms","start":"2026-07-02T05:08:57.14986Z","end":"2026-07-02T05:08:57.53457Z","steps":["trace[1727250964] 'agreement among raft nodes before linearized reading' (duration: 384.382505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:57.534702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:57.149855Z","time spent":"384.833355ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-07-02T05:08:57.537817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.810237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:08:57.53787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.401498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:08:57.537909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.521241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:08:57.537953Z","caller":"traceutil/trace.go:171","msg":"trace[1565709390] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"380.544212ms","start":"2026-07-02T05:08:57.157387Z","end":"2026-07-02T05:08:57.537931Z","steps":["trace[1565709390] 'agreement among raft nodes before linearized reading' (duration: 380.398558ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:08:57.537914Z","caller":"traceutil/trace.go:171","msg":"trace[39996766] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"387.934011ms","start":"2026-07-02T05:08:57.149954Z","end":"2026-07-02T05:08:57.537888Z","steps":["trace[39996766] 'agreement among raft nodes before linearized reading' (duration: 387.727886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:57.538029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:57.149945Z","time spent":"388.069174ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-07-02T05:08:57.538026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:57.157368Z","time spent":"380.638334ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-07-02T05:08:57.538072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.937149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:08:57.538032Z","caller":"traceutil/trace.go:171","msg":"trace[411399474] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"381.73893ms","start":"2026-07-02T05:08:57.156234Z","end":"2026-07-02T05:08:57.537973Z","steps":["trace[411399474] 'process raft request' (duration: 381.31055ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:08:57.538124Z","caller":"traceutil/trace.go:171","msg":"trace[1285940752] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"188.041041ms","start":"2026-07-02T05:08:57.350065Z","end":"2026-07-02T05:08:57.538106Z","steps":["trace[1285940752] 'agreement among raft nodes before linearized reading' (duration: 187.968729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:57.537847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.697384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:08:57.53821Z","caller":"traceutil/trace.go:171","msg":"trace[108968439] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"376.077112ms","start":"2026-07-02T05:08:57.162119Z","end":"2026-07-02T05:08:57.538196Z","steps":["trace[108968439] 'agreement among raft nodes before linearized reading' (duration: 375.686813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:57.538212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.733294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:08:57.53825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:57.162111Z","time spent":"376.128833ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-07-02T05:08:57.538253Z","caller":"traceutil/trace.go:171","msg":"trace[1266804679] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"375.781735ms","start":"2026-07-02T05:08:57.162461Z","end":"2026-07-02T05:08:57.538242Z","steps":["trace[1266804679] 'agreement among raft nodes before linearized reading' (duration: 375.726014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:57.538259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:57.156203Z","time spent":"381.945155ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:08:57.538269Z","caller":"traceutil/trace.go:171","msg":"trace[1572468903] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"380.8936ms","start":"2026-07-02T05:08:57.157363Z","end":"2026-07-02T05:08:57.538256Z","steps":["trace[1572468903] 'agreement among raft nodes before linearized reading' (duration: 380.48374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:57.538322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:57.162455Z","time spent":"375.824836ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-07-02T05:08:57.538356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:57.15735Z","time spent":"380.995373ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-02T05:08:57.538507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.362611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:08:57.538581Z","caller":"traceutil/trace.go:171","msg":"trace[1256355801] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"388.487074ms","start":"2026-07-02T05:08:57.150072Z","end":"2026-07-02T05:08:57.538559Z","steps":["trace[1256355801] 'agreement among raft nodes before linearized reading' (duration: 387.809218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:57.538621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:57.149702Z","time spent":"388.908574ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-07-02T05:08:57.538805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.614395ms","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-07-02T05:08:57.538909Z","caller":"traceutil/trace.go:171","msg":"trace[141817392] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"376.78292ms","start":"2026-07-02T05:08:57.1621Z","end":"2026-07-02T05:08:57.538882Z","steps":["trace[141817392] 'agreement among raft nodes before linearized reading' (duration: 375.639712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:57.539099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:57.162078Z","time spent":"377.004535ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-07-02T05:09:10.362774Z","caller":"traceutil/trace.go:171","msg":"trace[895221547] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"219.513926ms","start":"2026-07-02T05:09:10.143177Z","end":"2026-07-02T05:09:10.362691Z","steps":["trace[895221547] 'process raft request' (duration: 157.179741ms)","trace[895221547] 'compare' (duration: 61.920895ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:09:11.253554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.045924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:09:11.253712Z","caller":"traceutil/trace.go:171","msg":"trace[2138961951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"664.28049ms","start":"2026-07-02T05:09:10.589399Z","end":"2026-07-02T05:09:11.253679Z","steps":["trace[2138961951] 'range keys from in-memory index tree' (duration: 663.926601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:11.253773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:10.589343Z","time spent":"664.414322ms","remote":"127.0.0.1:42278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:09:11.605311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053750646760061,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:09:12.10597Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053750646760061,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:09:12.606847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053750646760061,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:09:13.107423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053750646760061,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:09:13.25595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000061534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-02T05:09:13.25607Z","caller":"traceutil/trace.go:171","msg":"trace[1436626328] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000260548s","start":"2026-07-02T05:09:11.255782Z","end":"2026-07-02T05:09:13.256043Z","steps":["trace[1436626328] 'agreement among raft nodes before linearized reading' (duration: 2.000105855s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:13.256136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:11.255754Z","time spent":"2.000370111s","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:09:13.31303Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.37544155s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T05:09:13.313496Z","caller":"traceutil/trace.go:171","msg":"trace[642242512] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"2.376012864s","start":"2026-07-02T05:09:10.937453Z","end":"2026-07-02T05:09:13.313466Z","steps":["trace[642242512] 'process raft request' (duration: 2.37582244s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:13.313725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:10.937421Z","time spent":"2.376175518s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:09:13.532816Z","caller":"traceutil/trace.go:171","msg":"trace[213616337] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"2.160262219s","start":"2026-07-02T05:09:11.372527Z","end":"2026-07-02T05:09:13.532789Z","steps":["trace[213616337] 'process raft request' (duration: 2.159945661s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:13.533021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.428251774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-02T05:09:13.533027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:11.372492Z","time spent":"2.160413292s","remote":"127.0.0.1:42512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:09:13.533065Z","caller":"traceutil/trace.go:171","msg":"trace[434584970] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:805; }","duration":"2.428345106s","start":"2026-07-02T05:09:11.104709Z","end":"2026-07-02T05:09:13.533054Z","steps":["trace[434584970] 'agreement among raft nodes before linearized reading' (duration: 2.428154992s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:13.5331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:11.104687Z","time spent":"2.428405157s","remote":"127.0.0.1:42316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"info","ts":"2026-07-02T05:09:13.532786Z","caller":"traceutil/trace.go:171","msg":"trace[751299985] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"2.427983258s","start":"2026-07-02T05:09:11.104766Z","end":"2026-07-02T05:09:13.532749Z","steps":["trace[751299985] 'read index received' (duration: 2.208556473s)","trace[751299985] 'applied index is now lower than readState.Index' (duration: 219.425155ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:09:13.53338Z","caller":"traceutil/trace.go:171","msg":"trace[1627839295] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"271.983623ms","start":"2026-07-02T05:09:13.261384Z","end":"2026-07-02T05:09:13.533367Z","steps":["trace[1627839295] 'process raft request' (duration: 271.299926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:13.533451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.924500857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T05:09:13.533533Z","caller":"traceutil/trace.go:171","msg":"trace[568087483] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"1.924690341s","start":"2026-07-02T05:09:11.608819Z","end":"2026-07-02T05:09:13.53351Z","steps":["trace[568087483] 'agreement among raft nodes before linearized reading' (duration: 1.924445405s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:13.53358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:11.608802Z","time spent":"1.924768164s","remote":"127.0.0.1:42546","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-07-02T05:09:13.533684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.877568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:09:13.53375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475297277s","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-07-02T05:09:13.533785Z","caller":"traceutil/trace.go:171","msg":"trace[1076529641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"1.475395949s","start":"2026-07-02T05:09:12.058378Z","end":"2026-07-02T05:09:13.533774Z","steps":["trace[1076529641] 'agreement among raft nodes before linearized reading' (duration: 1.475261166s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:13.533815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:12.058344Z","time spent":"1.47546258s","remote":"127.0.0.1:42546","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-07-02T05:09:13.533848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522578355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T05:09:13.533879Z","caller":"traceutil/trace.go:171","msg":"trace[1599464784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"1.522658687s","start":"2026-07-02T05:09:12.011211Z","end":"2026-07-02T05:09:13.53387Z","steps":["trace[1599464784] 'agreement among raft nodes before linearized reading' (duration: 1.522568215s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:13.5339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.812108054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:09:13.533944Z","caller":"traceutil/trace.go:171","msg":"trace[310547707] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:805; }","duration":"1.812182236s","start":"2026-07-02T05:09:11.721747Z","end":"2026-07-02T05:09:13.53393Z","steps":["trace[310547707] 'agreement among raft nodes before linearized reading' (duration: 1.812114075s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:13.533979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:11.721734Z","time spent":"1.812237147s","remote":"127.0.0.1:42496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:09:13.533986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49519892s","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-07-02T05:09:13.534015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475203973s","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-07-02T05:09:13.534021Z","caller":"traceutil/trace.go:171","msg":"trace[1861210383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"1.495284992s","start":"2026-07-02T05:09:12.038727Z","end":"2026-07-02T05:09:13.534012Z","steps":["trace[1861210383] 'agreement among raft nodes before linearized reading' (duration: 1.49520103s)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:13.534046Z","caller":"traceutil/trace.go:171","msg":"trace[1894872288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"1.475255305s","start":"2026-07-02T05:09:12.058781Z","end":"2026-07-02T05:09:13.534036Z","steps":["trace[1894872288] 'agreement among raft nodes before linearized reading' (duration: 1.475166873s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:13.534049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:12.038697Z","time spent":"1.495346414s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:09:13.53408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:12.05877Z","time spent":"1.475295785s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T05:09:13.533909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:12.011179Z","time spent":"1.522722638s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T05:09:13.533745Z","caller":"traceutil/trace.go:171","msg":"trace[764223388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"266.96859ms","start":"2026-07-02T05:09:13.266766Z","end":"2026-07-02T05:09:13.533735Z","steps":["trace[764223388] 'agreement among raft nodes before linearized reading' (duration: 266.886579ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:13.639262Z","caller":"traceutil/trace.go:171","msg":"trace[1192507647] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"102.671606ms","start":"2026-07-02T05:09:13.536538Z","end":"2026-07-02T05:09:13.639209Z","steps":["trace[1192507647] 'read index received' (duration: 102.126833ms)","trace[1192507647] 'applied index is now lower than readState.Index' (duration: 542.303µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:09:13.639476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.881012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:09:13.639609Z","caller":"traceutil/trace.go:171","msg":"trace[546652359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"103.067516ms","start":"2026-07-02T05:09:13.536512Z","end":"2026-07-02T05:09:13.639579Z","steps":["trace[546652359] 'agreement among raft nodes before linearized reading' (duration: 102.81843ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:13.696577Z","caller":"traceutil/trace.go:171","msg":"trace[921396552] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"157.374715ms","start":"2026-07-02T05:09:13.539167Z","end":"2026-07-02T05:09:13.696542Z","steps":["trace[921396552] 'process raft request' (duration: 157.017457ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:13.696578Z","caller":"traceutil/trace.go:171","msg":"trace[1171565841] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"156.580146ms","start":"2026-07-02T05:09:13.539961Z","end":"2026-07-02T05:09:13.696542Z","steps":["trace[1171565841] 'process raft request' (duration: 156.460094ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:13.696956Z","caller":"traceutil/trace.go:171","msg":"trace[589371213] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"156.762281ms","start":"2026-07-02T05:09:13.540162Z","end":"2026-07-02T05:09:13.696925Z","steps":["trace[589371213] 'process raft request' (duration: 156.31319ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:13.697021Z","caller":"traceutil/trace.go:171","msg":"trace[1244506191] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"156.515495ms","start":"2026-07-02T05:09:13.540486Z","end":"2026-07-02T05:09:13.697001Z","steps":["trace[1244506191] 'process raft request' (duration: 156.448513ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:13.697124Z","caller":"traceutil/trace.go:171","msg":"trace[320216451] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"156.897154ms","start":"2026-07-02T05:09:13.54021Z","end":"2026-07-02T05:09:13.697107Z","steps":["trace[320216451] 'process raft request' (duration: 156.602407ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:13.762725Z","caller":"traceutil/trace.go:171","msg":"trace[268419597] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"121.512945ms","start":"2026-07-02T05:09:13.641195Z","end":"2026-07-02T05:09:13.762708Z","steps":["trace[268419597] 'process raft request' (duration: 121.375292ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:14.302156Z","caller":"traceutil/trace.go:171","msg":"trace[235011476] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"115.510968ms","start":"2026-07-02T05:09:14.186619Z","end":"2026-07-02T05:09:14.30213Z","steps":["trace[235011476] 'process raft request' (duration: 115.352854ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:15.943588Z","caller":"traceutil/trace.go:171","msg":"trace[1360407377] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"236.674773ms","start":"2026-07-02T05:09:15.706886Z","end":"2026-07-02T05:09:15.94356Z","steps":["trace[1360407377] 'process raft request' (duration: 230.002381ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:15.94396Z","caller":"traceutil/trace.go:171","msg":"trace[1636177319] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"236.611022ms","start":"2026-07-02T05:09:15.707324Z","end":"2026-07-02T05:09:15.943935Z","steps":["trace[1636177319] 'process raft request' (duration: 236.180802ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:15.943985Z","caller":"traceutil/trace.go:171","msg":"trace[347891594] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"235.932815ms","start":"2026-07-02T05:09:15.708013Z","end":"2026-07-02T05:09:15.943946Z","steps":["trace[347891594] 'process raft request' (duration: 235.871964ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:15.944068Z","caller":"traceutil/trace.go:171","msg":"trace[803979791] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"236.536181ms","start":"2026-07-02T05:09:15.707512Z","end":"2026-07-02T05:09:15.944048Z","steps":["trace[803979791] 'process raft request' (duration: 236.204612ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:48.3084Z","caller":"traceutil/trace.go:171","msg":"trace[273191877] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"167.238033ms","start":"2026-07-02T05:09:48.141139Z","end":"2026-07-02T05:09:48.308377Z","steps":["trace[273191877] 'process raft request' (duration: 167.198351ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:48.308768Z","caller":"traceutil/trace.go:171","msg":"trace[1074756870] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"167.632543ms","start":"2026-07-02T05:09:48.141104Z","end":"2026-07-02T05:09:48.308737Z","steps":["trace[1074756870] 'process raft request' (duration: 167.105829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:49.251139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.342446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:09:49.25125Z","caller":"traceutil/trace.go:171","msg":"trace[1309453095] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"663.424467ms","start":"2026-07-02T05:09:48.587809Z","end":"2026-07-02T05:09:49.251234Z","steps":["trace[1309453095] 'read index received' (duration: 287.792474ms)","trace[1309453095] 'applied index is now lower than readState.Index' (duration: 375.630613ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:09:49.251279Z","caller":"traceutil/trace.go:171","msg":"trace[987281203] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"818.139103ms","start":"2026-07-02T05:09:48.433111Z","end":"2026-07-02T05:09:49.251251Z","steps":["trace[987281203] 'process raft request' (duration: 442.640173ms)","trace[987281203] 'compare' (duration: 375.206933ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:09:49.251304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.507259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:09:49.251338Z","caller":"traceutil/trace.go:171","msg":"trace[1676346069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"663.579151ms","start":"2026-07-02T05:09:48.58775Z","end":"2026-07-02T05:09:49.251329Z","steps":["trace[1676346069] 'agreement among raft nodes before linearized reading' (duration: 663.526799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:49.251373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:48.587724Z","time spent":"663.639452ms","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:09:49.251405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:48.433086Z","time spent":"818.248946ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:09:50.164421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.155052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:09:50.164506Z","caller":"traceutil/trace.go:171","msg":"trace[1152956445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"574.343066ms","start":"2026-07-02T05:09:49.590144Z","end":"2026-07-02T05:09:50.164487Z","steps":["trace[1152956445] 'range keys from in-memory index tree' (duration: 574.09092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:50.164553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:49.590125Z","time spent":"574.417049ms","remote":"127.0.0.1:42278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:09:50.164526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.196305ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:09:50.164618Z","caller":"traceutil/trace.go:171","msg":"trace[1674174667] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1022; }","duration":"313.310498ms","start":"2026-07-02T05:09:49.851291Z","end":"2026-07-02T05:09:50.164602Z","steps":["trace[1674174667] 'range keys from in-memory index tree' (duration: 313.184155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:50.16478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.484529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:09:50.164845Z","caller":"traceutil/trace.go:171","msg":"trace[662158407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1022; }","duration":"196.619542ms","start":"2026-07-02T05:09:49.968209Z","end":"2026-07-02T05:09:50.164828Z","steps":["trace[662158407] 'range keys from in-memory index tree' (duration: 196.300545ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:09:58.767038Z","caller":"traceutil/trace.go:171","msg":"trace[497458555] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"401.832655ms","start":"2026-07-02T05:09:58.365176Z","end":"2026-07-02T05:09:58.767009Z","steps":["trace[497458555] 'process raft request' (duration: 401.640531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:58.767217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:58.365134Z","time spent":"401.995239ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:09:58.768589Z","caller":"traceutil/trace.go:171","msg":"trace[1218165890] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"401.705021ms","start":"2026-07-02T05:09:58.36686Z","end":"2026-07-02T05:09:58.768565Z","steps":["trace[1218165890] 'process raft request' (duration: 401.336122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:58.768711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:09:58.366833Z","time spent":"401.820384ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:14.533569Z","caller":"traceutil/trace.go:171","msg":"trace[771658374] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"241.564031ms","start":"2026-07-02T05:10:14.291958Z","end":"2026-07-02T05:10:14.533522Z","steps":["trace[771658374] 'process raft request' (duration: 241.353246ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:15.410579Z","caller":"traceutil/trace.go:171","msg":"trace[1852433825] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1145; }","duration":"1.117451668s","start":"2026-07-02T05:10:14.293096Z","end":"2026-07-02T05:10:15.410548Z","steps":["trace[1852433825] 'read index received' (duration: 240.281751ms)","trace[1852433825] 'applied index is now lower than readState.Index' (duration: 877.168847ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:15.410692Z","caller":"traceutil/trace.go:171","msg":"trace[574921429] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"912.644805ms","start":"2026-07-02T05:10:14.497999Z","end":"2026-07-02T05:10:15.410644Z","steps":["trace[574921429] 'process raft request' (duration: 912.42843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:15.410828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.428289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:10:15.410954Z","caller":"traceutil/trace.go:171","msg":"trace[653005459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1104; }","duration":"820.610673ms","start":"2026-07-02T05:10:14.590322Z","end":"2026-07-02T05:10:15.410933Z","steps":["trace[653005459] 'agreement among raft nodes before linearized reading' (duration: 820.431649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:15.411002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:14.590276Z","time spent":"820.714896ms","remote":"127.0.0.1:42278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:10:15.410996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:14.497962Z","time spent":"912.83794ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:15.410595Z","caller":"traceutil/trace.go:171","msg":"trace[649828911] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"1.118481183s","start":"2026-07-02T05:10:14.292071Z","end":"2026-07-02T05:10:15.410552Z","steps":["trace[649828911] 'process raft request' (duration: 1.065874066s)","trace[649828911] 'compare' (duration: 51.703975ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:15.411254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:14.292028Z","time spent":"1.119108738s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:10:15.41136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.772753ms","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-07-02T05:10:15.411418Z","caller":"traceutil/trace.go:171","msg":"trace[91306754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1104; }","duration":"562.846545ms","start":"2026-07-02T05:10:14.848557Z","end":"2026-07-02T05:10:15.411403Z","steps":["trace[91306754] 'agreement among raft nodes before linearized reading' (duration: 562.65042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:15.411459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:14.848548Z","time spent":"562.903017ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:10:15.411732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.84127ms","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-07-02T05:10:15.411769Z","caller":"traceutil/trace.go:171","msg":"trace[352890765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1104; }","duration":"563.923962ms","start":"2026-07-02T05:10:14.847835Z","end":"2026-07-02T05:10:15.411759Z","steps":["trace[352890765] 'agreement among raft nodes before linearized reading' (duration: 563.824549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:15.411797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:14.847809Z","time spent":"563.982083ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:10:15.411835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.652675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:10:15.411898Z","caller":"traceutil/trace.go:171","msg":"trace[1153711740] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1104; }","duration":"695.756118ms","start":"2026-07-02T05:10:14.716121Z","end":"2026-07-02T05:10:15.411877Z","steps":["trace[1153711740] 'agreement among raft nodes before linearized reading' (duration: 695.598694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:15.411953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:14.716106Z","time spent":"695.83534ms","remote":"127.0.0.1:42654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:10:15.41287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119748714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T05:10:15.412936Z","caller":"traceutil/trace.go:171","msg":"trace[1135547723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1104; }","duration":"1.119848637s","start":"2026-07-02T05:10:14.29307Z","end":"2026-07-02T05:10:15.412919Z","steps":["trace[1135547723] 'agreement among raft nodes before linearized reading' (duration: 1.117655313s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:15.412981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:14.293059Z","time spent":"1.119910678s","remote":"127.0.0.1:42546","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-07-02T05:10:16.705331Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053750646760818,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T05:10:17.055477Z","caller":"traceutil/trace.go:171","msg":"trace[672961871] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1152; }","duration":"851.09728ms","start":"2026-07-02T05:10:16.204349Z","end":"2026-07-02T05:10:17.055446Z","steps":["trace[672961871] 'read index received' (duration: 761.878316ms)","trace[672961871] 'applied index is now lower than readState.Index' (duration: 89.217714ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:17.05559Z","caller":"traceutil/trace.go:171","msg":"trace[409832501] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"604.409632ms","start":"2026-07-02T05:10:16.451145Z","end":"2026-07-02T05:10:17.055555Z","steps":["trace[409832501] 'process raft request' (duration: 604.066233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:17.055692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.333085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:10:17.055761Z","caller":"traceutil/trace.go:171","msg":"trace[177271628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1109; }","duration":"851.417027ms","start":"2026-07-02T05:10:16.20433Z","end":"2026-07-02T05:10:17.055747Z","steps":["trace[177271628] 'agreement among raft nodes before linearized reading' (duration: 851.288814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:17.055805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:16.204241Z","time spent":"851.556442ms","remote":"127.0.0.1:42278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:10:17.055873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.634048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-02T05:10:17.05588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:16.451109Z","time spent":"604.658058ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:17.055932Z","caller":"traceutil/trace.go:171","msg":"trace[1828444645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1109; }","duration":"517.769291ms","start":"2026-07-02T05:10:16.538147Z","end":"2026-07-02T05:10:17.055916Z","steps":["trace[1828444645] 'agreement among raft nodes before linearized reading' (duration: 517.537176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:17.055972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.480397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:10:17.055989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:16.538112Z","time spent":"517.866124ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T05:10:17.056073Z","caller":"traceutil/trace.go:171","msg":"trace[973477353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1109; }","duration":"466.656641ms","start":"2026-07-02T05:10:16.589395Z","end":"2026-07-02T05:10:17.056052Z","steps":["trace[973477353] 'agreement among raft nodes before linearized reading' (duration: 466.466336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:17.056127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:16.589361Z","time spent":"466.754924ms","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:10:19.204973Z","caller":"traceutil/trace.go:171","msg":"trace[1417771919] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"116.376498ms","start":"2026-07-02T05:10:19.088573Z","end":"2026-07-02T05:10:19.20495Z","steps":["trace[1417771919] 'process raft request' (duration: 116.219474ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:19.600683Z","caller":"traceutil/trace.go:171","msg":"trace[183168752] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"118.751376ms","start":"2026-07-02T05:10:19.48191Z","end":"2026-07-02T05:10:19.600661Z","steps":["trace[183168752] 'process raft request' (duration: 110.982436ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:19.600895Z","caller":"traceutil/trace.go:171","msg":"trace[2112244634] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"114.40053ms","start":"2026-07-02T05:10:19.486482Z","end":"2026-07-02T05:10:19.600882Z","steps":["trace[2112244634] 'process raft request' (duration: 114.146444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:36.495744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053750646761080,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T05:10:36.703306Z","caller":"traceutil/trace.go:171","msg":"trace[1583218409] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"776.121506ms","start":"2026-07-02T05:10:35.927145Z","end":"2026-07-02T05:10:36.703267Z","steps":["trace[1583218409] 'process raft request' (duration: 775.897291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:36.703547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:35.927114Z","time spent":"776.309931ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:36.862191Z","caller":"traceutil/trace.go:171","msg":"trace[76225865] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1273; }","duration":"867.285768ms","start":"2026-07-02T05:10:35.994865Z","end":"2026-07-02T05:10:36.862151Z","steps":["trace[76225865] 'read index received' (duration: 708.107572ms)","trace[76225865] 'applied index is now lower than readState.Index' (duration: 159.176016ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:36.862522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.628586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T05:10:36.862569Z","caller":"traceutil/trace.go:171","msg":"trace[1511545769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"867.741219ms","start":"2026-07-02T05:10:35.994815Z","end":"2026-07-02T05:10:36.862556Z","steps":["trace[1511545769] 'agreement among raft nodes before linearized reading' (duration: 867.453582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:36.862542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.584382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:10:36.862611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:35.994802Z","time spent":"867.80074ms","remote":"127.0.0.1:42546","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-07-02T05:10:36.862648Z","caller":"traceutil/trace.go:171","msg":"trace[1940844791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"272.733056ms","start":"2026-07-02T05:10:36.589887Z","end":"2026-07-02T05:10:36.86262Z","steps":["trace[1940844791] 'agreement among raft nodes before linearized reading' (duration: 272.556291ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:49.147616Z","caller":"traceutil/trace.go:171","msg":"trace[883549125] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"200.723023ms","start":"2026-07-02T05:10:48.94686Z","end":"2026-07-02T05:10:49.147583Z","steps":["trace[883549125] 'process raft request' (duration: 200.529778ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:49.956513Z","caller":"traceutil/trace.go:171","msg":"trace[2095225801] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"250.794889ms","start":"2026-07-02T05:10:49.705687Z","end":"2026-07-02T05:10:49.956482Z","steps":["trace[2095225801] 'process raft request' (duration: 250.625145ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:49.960304Z","caller":"traceutil/trace.go:171","msg":"trace[1099422364] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"162.856137ms","start":"2026-07-02T05:10:49.79742Z","end":"2026-07-02T05:10:49.960276Z","steps":["trace[1099422364] 'read index received' (duration: 159.047824ms)","trace[1099422364] 'applied index is now lower than readState.Index' (duration: 3.807023ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:49.960433Z","caller":"traceutil/trace.go:171","msg":"trace[427099527] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"209.122038ms","start":"2026-07-02T05:10:49.7513Z","end":"2026-07-02T05:10:49.960422Z","steps":["trace[427099527] 'process raft request' (duration: 208.80204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.960697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.43965ms","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-07-02T05:10:49.96126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.820641ms","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-07-02T05:10:49.961636Z","caller":"traceutil/trace.go:171","msg":"trace[733706325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1268; }","duration":"164.237071ms","start":"2026-07-02T05:10:49.797374Z","end":"2026-07-02T05:10:49.961612Z","steps":["trace[733706325] 'agreement among raft nodes before linearized reading' (duration: 163.131793ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:49.961565Z","caller":"traceutil/trace.go:171","msg":"trace[296312920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1268; }","duration":"155.332173ms","start":"2026-07-02T05:10:49.806191Z","end":"2026-07-02T05:10:49.961523Z","steps":["trace[296312920] 'agreement among raft nodes before linearized reading' (duration: 154.336498ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:51.313922Z","caller":"traceutil/trace.go:171","msg":"trace[231814639] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"161.860041ms","start":"2026-07-02T05:10:51.152037Z","end":"2026-07-02T05:10:51.313898Z","steps":["trace[231814639] 'read index received' (duration: 161.648686ms)","trace[231814639] 'applied index is now lower than readState.Index' (duration: 206.775µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:51.314277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.226321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T05:10:51.314329Z","caller":"traceutil/trace.go:171","msg":"trace[1058365499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"162.342164ms","start":"2026-07-02T05:10:51.151978Z","end":"2026-07-02T05:10:51.31432Z","steps":["trace[1058365499] 'agreement among raft nodes before linearized reading' (duration: 162.139138ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:57.255857Z","caller":"traceutil/trace.go:171","msg":"trace[1484545970] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"244.650148ms","start":"2026-07-02T05:10:57.01114Z","end":"2026-07-02T05:10:57.25579Z","steps":["trace[1484545970] 'process raft request' (duration: 244.386421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:57.584411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.059154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:10:57.584497Z","caller":"traceutil/trace.go:171","msg":"trace[875218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1317; }","duration":"110.234968ms","start":"2026-07-02T05:10:57.474242Z","end":"2026-07-02T05:10:57.584477Z","steps":["trace[875218] 'range keys from in-memory index tree' (duration: 109.608542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:57.584466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.597637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T05:10:57.584611Z","caller":"traceutil/trace.go:171","msg":"trace[1658965879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1317; }","duration":"215.786082ms","start":"2026-07-02T05:10:57.368789Z","end":"2026-07-02T05:10:57.584575Z","steps":["trace[1658965879] 'range keys from in-memory index tree' (duration: 215.269909ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:57.880457Z","caller":"traceutil/trace.go:171","msg":"trace[979995508] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"287.298271ms","start":"2026-07-02T05:10:57.59313Z","end":"2026-07-02T05:10:57.880429Z","steps":["trace[979995508] 'process raft request' (duration: 287.082756ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:58.184518Z","caller":"traceutil/trace.go:171","msg":"trace[1706823597] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"111.351376ms","start":"2026-07-02T05:10:58.07313Z","end":"2026-07-02T05:10:58.184481Z","steps":["trace[1706823597] 'process raft request' (duration: 40.669276ms)","trace[1706823597] 'compare' (duration: 70.375503ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:58.796656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.173936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:10:58.796735Z","caller":"traceutil/trace.go:171","msg":"trace[749839044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"208.291129ms","start":"2026-07-02T05:10:58.588426Z","end":"2026-07-02T05:10:58.796717Z","steps":["trace[749839044] 'range keys from in-memory index tree' (duration: 208.078784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:58.797124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.365021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:10:58.79714Z","caller":"traceutil/trace.go:171","msg":"trace[880444853] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1325; }","duration":"206.397073ms","start":"2026-07-02T05:10:58.590738Z","end":"2026-07-02T05:10:58.797135Z","steps":["trace[880444853] 'count revisions from in-memory index tree' (duration: 206.31669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:00.228862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053750646761320,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T05:11:00.417926Z","caller":"traceutil/trace.go:171","msg":"trace[1996427969] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"757.737376ms","start":"2026-07-02T05:10:59.660164Z","end":"2026-07-02T05:11:00.417902Z","steps":["trace[1996427969] 'process raft request' (duration: 757.589463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:00.418086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:59.66014Z","time spent":"757.86117ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:00.745945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.327108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:11:00.74618Z","caller":"traceutil/trace.go:171","msg":"trace[914829832] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"700.014353ms","start":"2026-07-02T05:11:00.046133Z","end":"2026-07-02T05:11:00.746147Z","steps":["trace[914829832] 'process raft request' (duration: 538.368068ms)","trace[914829832] 'compare' (duration: 161.139623ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:11:00.746349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:00.046103Z","time spent":"700.135325ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:11:00.747009Z","caller":"traceutil/trace.go:171","msg":"trace[1122667627] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"1.018229292s","start":"2026-07-02T05:10:59.728755Z","end":"2026-07-02T05:11:00.746984Z","steps":["trace[1122667627] 'read index received' (duration: 689.25734ms)","trace[1122667627] 'applied index is now lower than readState.Index' (duration: 328.970692ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:11:00.747245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018487109s","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-07-02T05:11:00.74728Z","caller":"traceutil/trace.go:171","msg":"trace[1312643302] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1328; }","duration":"1.01855163s","start":"2026-07-02T05:10:59.72872Z","end":"2026-07-02T05:11:00.747272Z","steps":["trace[1312643302] 'agreement among raft nodes before linearized reading' (duration: 1.018361176s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:00.747299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:59.7287Z","time spent":"1.018595012s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-02T05:11:00.747233Z","caller":"traceutil/trace.go:171","msg":"trace[1322708866] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"437.94831ms","start":"2026-07-02T05:11:00.309072Z","end":"2026-07-02T05:11:00.74702Z","steps":["trace[1322708866] 'process raft request' (duration: 436.999826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:00.747476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.324986ms","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-07-02T05:11:00.747482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.297946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T05:11:00.7475Z","caller":"traceutil/trace.go:171","msg":"trace[1823347494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1328; }","duration":"710.384588ms","start":"2026-07-02T05:11:00.037109Z","end":"2026-07-02T05:11:00.747493Z","steps":["trace[1823347494] 'agreement among raft nodes before linearized reading' (duration: 710.329836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:00.747479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.278437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-02T05:11:00.747551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.18017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:11:00.747553Z","caller":"traceutil/trace.go:171","msg":"trace[704608494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1328; }","duration":"862.408119ms","start":"2026-07-02T05:10:59.885131Z","end":"2026-07-02T05:11:00.747539Z","steps":["trace[704608494] 'agreement among raft nodes before linearized reading' (duration: 862.130812ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:00.747576Z","caller":"traceutil/trace.go:171","msg":"trace[77537932] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1328; }","duration":"343.251761ms","start":"2026-07-02T05:11:00.404315Z","end":"2026-07-02T05:11:00.747567Z","steps":["trace[77537932] 'agreement among raft nodes before linearized reading' (duration: 343.221221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:00.747591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:00.404291Z","time spent":"343.297813ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-02T05:11:00.747582Z","caller":"traceutil/trace.go:171","msg":"trace[1565686749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1328; }","duration":"659.409629ms","start":"2026-07-02T05:11:00.088139Z","end":"2026-07-02T05:11:00.747548Z","steps":["trace[1565686749] 'agreement among raft nodes before linearized reading' (duration: 659.127832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:00.74747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:00.30905Z","time spent":"438.346319ms","remote":"127.0.0.1:42366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:11:00.747633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:59.885105Z","time spent":"862.48831ms","remote":"127.0.0.1:42546","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-07-02T05:11:00.747524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:00.037084Z","time spent":"710.436509ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T05:11:00.747639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:00.088121Z","time spent":"659.508692ms","remote":"127.0.0.1:42546","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-07-02T05:11:00.747461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.160507ms","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-07-02T05:11:00.747541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.06458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:11:00.747952Z","caller":"traceutil/trace.go:171","msg":"trace[1257362870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1328; }","duration":"557.67111ms","start":"2026-07-02T05:11:00.190246Z","end":"2026-07-02T05:11:00.747917Z","steps":["trace[1257362870] 'agreement among raft nodes before linearized reading' (duration: 557.014704ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:00.747984Z","caller":"traceutil/trace.go:171","msg":"trace[994695541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"158.484409ms","start":"2026-07-02T05:11:00.589444Z","end":"2026-07-02T05:11:00.747928Z","steps":["trace[994695541] 'agreement among raft nodes before linearized reading' (duration: 158.074889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:00.74806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:00.190235Z","time spent":"557.791743ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T05:11:01.09605Z","caller":"traceutil/trace.go:171","msg":"trace[1135922228] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"342.052012ms","start":"2026-07-02T05:11:00.753968Z","end":"2026-07-02T05:11:01.09602Z","steps":["trace[1135922228] 'process raft request' (duration: 341.677873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:01.096288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:00.753935Z","time spent":"342.230886ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:11:01.096594Z","caller":"traceutil/trace.go:171","msg":"trace[66701077] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"342.564616ms","start":"2026-07-02T05:11:00.753991Z","end":"2026-07-02T05:11:01.096556Z","steps":["trace[66701077] 'process raft request' (duration: 341.979021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:01.096791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:00.753974Z","time spent":"342.736039ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:11:01.098074Z","caller":"traceutil/trace.go:171","msg":"trace[897832394] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"342.313589ms","start":"2026-07-02T05:11:00.755738Z","end":"2026-07-02T05:11:01.098051Z","steps":["trace[897832394] 'process raft request' (duration: 342.211856ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:01.098048Z","caller":"traceutil/trace.go:171","msg":"trace[1993229480] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"343.901758ms","start":"2026-07-02T05:11:00.754106Z","end":"2026-07-02T05:11:01.098008Z","steps":["trace[1993229480] 'process raft request' (duration: 342.122884ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:01.098103Z","caller":"traceutil/trace.go:171","msg":"trace[626877574] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"342.359709ms","start":"2026-07-02T05:11:00.755703Z","end":"2026-07-02T05:11:01.098062Z","steps":["trace[626877574] 'process raft request' (duration: 342.214865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:01.098198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:00.75573Z","time spent":"342.398871ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:01.098248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:00.754086Z","time spent":"344.060681ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:01.098275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:00.755686Z","time spent":"342.495803ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:11:01.098655Z","caller":"traceutil/trace.go:171","msg":"trace[1251949460] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"309.394342ms","start":"2026-07-02T05:11:00.789252Z","end":"2026-07-02T05:11:01.098646Z","steps":["trace[1251949460] 'process raft request' (duration: 308.718936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:01.098696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:00.789227Z","time spent":"309.448954ms","remote":"127.0.0.1:42366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:11:01.46744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.417278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:11:01.467701Z","caller":"traceutil/trace.go:171","msg":"trace[692226902] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1390; }","duration":"355.895462ms","start":"2026-07-02T05:11:01.111785Z","end":"2026-07-02T05:11:01.467681Z","steps":["trace[692226902] 'read index received' (duration: 92.128846ms)","trace[692226902] 'applied index is now lower than readState.Index' (duration: 263.765576ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:11:01.467878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.103846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-02T05:11:01.467915Z","caller":"traceutil/trace.go:171","msg":"trace[1447461650] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:1338; }","duration":"356.176418ms","start":"2026-07-02T05:11:01.111725Z","end":"2026-07-02T05:11:01.467902Z","steps":["trace[1447461650] 'agreement among raft nodes before linearized reading' (duration: 356.018625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:01.467979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:01.111708Z","time spent":"356.262291ms","remote":"127.0.0.1:42316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"info","ts":"2026-07-02T05:11:01.467918Z","caller":"traceutil/trace.go:171","msg":"trace[1930503040] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"364.629205ms","start":"2026-07-02T05:11:01.103235Z","end":"2026-07-02T05:11:01.467864Z","steps":["trace[1930503040] 'process raft request' (duration: 364.322298ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:01.468163Z","caller":"traceutil/trace.go:171","msg":"trace[2115166445] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"362.563434ms","start":"2026-07-02T05:11:01.105578Z","end":"2026-07-02T05:11:01.468142Z","steps":["trace[2115166445] 'process raft request' (duration: 362.028181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:01.468315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:01.10554Z","time spent":"362.688627ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:11:01.468159Z","caller":"traceutil/trace.go:171","msg":"trace[669509976] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"365.26858ms","start":"2026-07-02T05:11:01.102844Z","end":"2026-07-02T05:11:01.468112Z","steps":["trace[669509976] 'process raft request' (duration: 101.099094ms)","trace[669509976] 'compare' (duration: 263.214113ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:11:01.468458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:01.102831Z","time spent":"365.597859ms","remote":"127.0.0.1:42366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":843,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:11:01.468749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:01.103208Z","time spent":"365.382264ms","remote":"127.0.0.1:42366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:11:01.687154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.775737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:11:01.687345Z","caller":"traceutil/trace.go:171","msg":"trace[504687995] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"151.932648ms","start":"2026-07-02T05:11:01.535377Z","end":"2026-07-02T05:11:01.68731Z","steps":["trace[504687995] 'process raft request' (duration: 34.877864ms)","trace[504687995] 'compare' (duration: 116.599033ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:11:02.626146Z","caller":"traceutil/trace.go:171","msg":"trace[790071148] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"100.956852ms","start":"2026-07-02T05:11:02.525154Z","end":"2026-07-02T05:11:02.626111Z","steps":["trace[790071148] 'read index received' (duration: 100.727066ms)","trace[790071148] 'applied index is now lower than readState.Index' (duration: 228.196µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:11:02.626265Z","caller":"traceutil/trace.go:171","msg":"trace[663310039] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"198.336525ms","start":"2026-07-02T05:11:02.427881Z","end":"2026-07-02T05:11:02.626218Z","steps":["trace[663310039] 'process raft request' (duration: 198.024356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:02.626431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.216848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:11:02.626628Z","caller":"traceutil/trace.go:171","msg":"trace[513036443] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1340; }","duration":"101.469744ms","start":"2026-07-02T05:11:02.525139Z","end":"2026-07-02T05:11:02.626608Z","steps":["trace[513036443] 'agreement among raft nodes before linearized reading' (duration: 101.107216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:03.1221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.838865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:11:03.122257Z","caller":"traceutil/trace.go:171","msg":"trace[1822268367] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"370.032076ms","start":"2026-07-02T05:11:02.752195Z","end":"2026-07-02T05:11:03.122227Z","steps":["trace[1822268367] 'process raft request' (duration: 72.943395ms)","trace[1822268367] 'compare' (duration: 296.641019ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:11:03.122443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:02.752157Z","time spent":"370.19421ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:03.968786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.092546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:11:03.969Z","caller":"traceutil/trace.go:171","msg":"trace[1440164433] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"840.82219ms","start":"2026-07-02T05:11:03.128145Z","end":"2026-07-02T05:11:03.968968Z","steps":["trace[1440164433] 'process raft request' (duration: 138.441708ms)","trace[1440164433] 'compare' (duration: 701.537822ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:11:03.969081Z","caller":"traceutil/trace.go:171","msg":"trace[1008262504] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1397; }","duration":"715.944225ms","start":"2026-07-02T05:11:03.253118Z","end":"2026-07-02T05:11:03.969062Z","steps":["trace[1008262504] 'read index received' (duration: 13.566352ms)","trace[1008262504] 'applied index is now lower than readState.Index' (duration: 702.376582ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:11:03.969154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:03.128127Z","time spent":"840.938204ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:11:03.969285Z","caller":"traceutil/trace.go:171","msg":"trace[1108979608] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"839.759665ms","start":"2026-07-02T05:11:03.129517Z","end":"2026-07-02T05:11:03.969276Z","steps":["trace[1108979608] 'process raft request' (duration: 839.509199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:03.969333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:03.129508Z","time spent":"839.802706ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:03.969379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.264873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:11:03.969418Z","caller":"traceutil/trace.go:171","msg":"trace[1580863337] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1345; }","duration":"716.381986ms","start":"2026-07-02T05:11:03.253021Z","end":"2026-07-02T05:11:03.969403Z","steps":["trace[1580863337] 'agreement among raft nodes before linearized reading' (duration: 716.313044ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:03.969449Z","caller":"traceutil/trace.go:171","msg":"trace[1564484718] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"841.126518ms","start":"2026-07-02T05:11:03.128313Z","end":"2026-07-02T05:11:03.96944Z","steps":["trace[1564484718] 'process raft request' (duration: 840.596205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:03.969477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:03.252994Z","time spent":"716.450887ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:11:03.969491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:03.128271Z","time spent":"841.195869ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:11:03.969578Z","caller":"traceutil/trace.go:171","msg":"trace[736794342] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"841.053556ms","start":"2026-07-02T05:11:03.128515Z","end":"2026-07-02T05:11:03.969569Z","steps":["trace[736794342] 'process raft request' (duration: 840.470512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:03.969617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:03.128508Z","time spent":"841.087416ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:03.969714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.156278ms","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-07-02T05:11:03.969744Z","caller":"traceutil/trace.go:171","msg":"trace[709473073] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1345; }","duration":"700.23125ms","start":"2026-07-02T05:11:03.269504Z","end":"2026-07-02T05:11:03.969735Z","steps":["trace[709473073] 'agreement among raft nodes before linearized reading' (duration: 700.181939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:03.969777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:03.269481Z","time spent":"700.283141ms","remote":"127.0.0.1:42418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:11:03.969999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.644789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:11:03.970038Z","caller":"traceutil/trace.go:171","msg":"trace[133634203] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1345; }","duration":"146.744051ms","start":"2026-07-02T05:11:03.823285Z","end":"2026-07-02T05:11:03.970029Z","steps":["trace[133634203] 'agreement among raft nodes before linearized reading' (duration: 146.680499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:03.97019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.218384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:11:03.97028Z","caller":"traceutil/trace.go:171","msg":"trace[401038217] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1345; }","duration":"375.378207ms","start":"2026-07-02T05:11:03.594881Z","end":"2026-07-02T05:11:03.97026Z","steps":["trace[401038217] 'agreement among raft nodes before linearized reading' (duration: 375.192913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:03.970348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:03.59483Z","time spent":"375.509571ms","remote":"127.0.0.1:42630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:11:03.970474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.380639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:11:03.970571Z","caller":"traceutil/trace.go:171","msg":"trace[1164209486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1345; }","duration":"380.482602ms","start":"2026-07-02T05:11:03.590059Z","end":"2026-07-02T05:11:03.970541Z","steps":["trace[1164209486] 'agreement among raft nodes before linearized reading' (duration: 379.999851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:03.970636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:03.589956Z","time spent":"380.665756ms","remote":"127.0.0.1:42278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:11:05.415271Z","caller":"traceutil/trace.go:171","msg":"trace[1476363037] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"287.298422ms","start":"2026-07-02T05:11:05.127946Z","end":"2026-07-02T05:11:05.415244Z","steps":["trace[1476363037] 'process raft request' (duration: 287.094507ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:06.879873Z","caller":"traceutil/trace.go:171","msg":"trace[1353657808] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"901.987701ms","start":"2026-07-02T05:11:05.977852Z","end":"2026-07-02T05:11:06.87984Z","steps":["trace[1353657808] 'process raft request' (duration: 901.738165ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:06.88007Z","caller":"traceutil/trace.go:171","msg":"trace[7396787] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1405; }","duration":"407.349093ms","start":"2026-07-02T05:11:06.472706Z","end":"2026-07-02T05:11:06.880055Z","steps":["trace[7396787] 'read index received' (duration: 406.910692ms)","trace[7396787] 'applied index is now lower than readState.Index' (duration: 436.651µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:11:06.88012Z","caller":"traceutil/trace.go:171","msg":"trace[1701609022] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"900.496514ms","start":"2026-07-02T05:11:05.979613Z","end":"2026-07-02T05:11:06.88011Z","steps":["trace[1701609022] 'process raft request' (duration: 900.186456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:06.880186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:05.979599Z","time spent":"900.553326ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:06.880072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:05.977836Z","time spent":"902.125114ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:11:06.879888Z","caller":"traceutil/trace.go:171","msg":"trace[340593368] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"901.584171ms","start":"2026-07-02T05:11:05.978255Z","end":"2026-07-02T05:11:06.879839Z","steps":["trace[340593368] 'process raft request' (duration: 901.502369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:06.880442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.718691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T05:11:06.880497Z","caller":"traceutil/trace.go:171","msg":"trace[1665135091] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1352; }","duration":"407.849154ms","start":"2026-07-02T05:11:06.472632Z","end":"2026-07-02T05:11:06.880481Z","steps":["trace[1665135091] 'agreement among raft nodes before linearized reading' (duration: 407.509166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:06.88057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:06.472606Z","time spent":"407.953087ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-02T05:11:06.880544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:05.978229Z","time spent":"902.186806ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:06.887433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.028402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:11:06.887507Z","caller":"traceutil/trace.go:171","msg":"trace[1924002204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1353; }","duration":"297.162345ms","start":"2026-07-02T05:11:06.590328Z","end":"2026-07-02T05:11:06.88749Z","steps":["trace[1924002204] 'agreement among raft nodes before linearized reading' (duration: 297.020761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:06.887718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.308759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:11:06.887863Z","caller":"traceutil/trace.go:171","msg":"trace[1551704963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1353; }","duration":"163.516104ms","start":"2026-07-02T05:11:06.724329Z","end":"2026-07-02T05:11:06.887845Z","steps":["trace[1551704963] 'agreement among raft nodes before linearized reading' (duration: 163.129185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:11.409492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053750646761451,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:11:11.91448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053750646761451,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:11:12.157072Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.248570644s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T05:11:12.158536Z","caller":"traceutil/trace.go:171","msg":"trace[354960483] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"1.250151012s","start":"2026-07-02T05:11:10.908367Z","end":"2026-07-02T05:11:12.158518Z","steps":["trace[354960483] 'process raft request' (duration: 1.249691501s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:12.158712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:10.908338Z","time spent":"1.250307026s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:11:12.160256Z","caller":"traceutil/trace.go:171","msg":"trace[1117059510] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1447; }","duration":"1.251605599s","start":"2026-07-02T05:11:10.908627Z","end":"2026-07-02T05:11:12.160232Z","steps":["trace[1117059510] 'read index received' (duration: 1.248671917s)","trace[1117059510] 'applied index is now lower than readState.Index' (duration: 2.931882ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:11:12.160402Z","caller":"traceutil/trace.go:171","msg":"trace[300538912] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"718.947809ms","start":"2026-07-02T05:11:11.441439Z","end":"2026-07-02T05:11:12.160387Z","steps":["trace[300538912] 'process raft request' (duration: 718.708064ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:12.160443Z","caller":"traceutil/trace.go:171","msg":"trace[940826706] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"1.248496762s","start":"2026-07-02T05:11:10.911916Z","end":"2026-07-02T05:11:12.160413Z","steps":["trace[940826706] 'process raft request' (duration: 1.246563494s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:12.160485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:11.441411Z","time spent":"719.023032ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:12.160602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:10.9119Z","time spent":"1.248597315s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:12.1609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252129631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:11:12.161004Z","caller":"traceutil/trace.go:171","msg":"trace[850463141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1393; }","duration":"1.252317006s","start":"2026-07-02T05:11:10.908666Z","end":"2026-07-02T05:11:12.160983Z","steps":["trace[850463141] 'agreement among raft nodes before linearized reading' (duration: 1.251950287s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:12.161054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:10.908643Z","time spent":"1.252400997s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T05:11:12.161168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.371965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-02T05:11:12.161267Z","caller":"traceutil/trace.go:171","msg":"trace[1248773636] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1393; }","duration":"269.543979ms","start":"2026-07-02T05:11:11.891695Z","end":"2026-07-02T05:11:12.161239Z","steps":["trace[1248773636] 'agreement among raft nodes before linearized reading' (duration: 269.246312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:12.161352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.860302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:11:12.16139Z","caller":"traceutil/trace.go:171","msg":"trace[1335747019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1393; }","duration":"572.946213ms","start":"2026-07-02T05:11:11.588431Z","end":"2026-07-02T05:11:12.161378Z","steps":["trace[1335747019] 'agreement among raft nodes before linearized reading' (duration: 572.897412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:12.161423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:11.5884Z","time spent":"573.016935ms","remote":"127.0.0.1:42278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:11:12.161434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219554333s","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-07-02T05:11:12.161466Z","caller":"traceutil/trace.go:171","msg":"trace[823454295] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1393; }","duration":"1.219619905s","start":"2026-07-02T05:11:10.941838Z","end":"2026-07-02T05:11:12.161458Z","steps":["trace[823454295] 'agreement among raft nodes before linearized reading' (duration: 1.219529303s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:12.161486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:10.941759Z","time spent":"1.219722648s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-02T05:11:12.16148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.268497ms","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-07-02T05:11:12.161536Z","caller":"traceutil/trace.go:171","msg":"trace[1385481469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1393; }","duration":"143.37756ms","start":"2026-07-02T05:11:12.018143Z","end":"2026-07-02T05:11:12.16152Z","steps":["trace[1385481469] 'agreement among raft nodes before linearized reading' (duration: 143.197255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:12.161553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.313448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T05:11:12.161579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130425802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-02T05:11:12.161588Z","caller":"traceutil/trace.go:171","msg":"trace[2099133373] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1393; }","duration":"230.417411ms","start":"2026-07-02T05:11:11.93116Z","end":"2026-07-02T05:11:12.161577Z","steps":["trace[2099133373] 'agreement among raft nodes before linearized reading' (duration: 230.342449ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:12.161601Z","caller":"traceutil/trace.go:171","msg":"trace[294886711] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1393; }","duration":"1.130506494s","start":"2026-07-02T05:11:11.031088Z","end":"2026-07-02T05:11:12.161595Z","steps":["trace[294886711] 'agreement among raft nodes before linearized reading' (duration: 1.130448063s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:12.16162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:11.031063Z","time spent":"1.130553636s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-02T05:11:12.161297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048750262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-02T05:11:12.16167Z","caller":"traceutil/trace.go:171","msg":"trace[1894010330] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:1393; }","duration":"1.049167572s","start":"2026-07-02T05:11:11.112498Z","end":"2026-07-02T05:11:12.161665Z","steps":["trace[1894010330] 'agreement among raft nodes before linearized reading' (duration: 1.048566608s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:12.161685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:11.112481Z","time spent":"1.049200064s","remote":"127.0.0.1:42316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-07-02T05:11:12.161249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252618283s","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-07-02T05:11:12.161801Z","caller":"traceutil/trace.go:171","msg":"trace[1668175719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1393; }","duration":"1.253207727s","start":"2026-07-02T05:11:10.908576Z","end":"2026-07-02T05:11:12.161784Z","steps":["trace[1668175719] 'agreement among raft nodes before linearized reading' (duration: 1.252600612s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:12.161837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:10.908563Z","time spent":"1.253262038s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:13:51.051711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.522027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:51.051774Z","caller":"traceutil/trace.go:171","msg":"trace[1904551513] linearizableReadLoop","detail":"{readStateIndex:2209; appliedIndex:2208; }","duration":"218.311371ms","start":"2026-07-02T05:13:50.833158Z","end":"2026-07-02T05:13:51.051469Z","steps":["trace[1904551513] 'read index received' (duration: 218.102875ms)","trace[1904551513] 'applied index is now lower than readState.Index' (duration: 206.956µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:51.05183Z","caller":"traceutil/trace.go:171","msg":"trace[803720635] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2120; }","duration":"218.715302ms","start":"2026-07-02T05:13:50.83309Z","end":"2026-07-02T05:13:51.051805Z","steps":["trace[803720635] 'agreement among raft nodes before linearized reading' (duration: 218.473246ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:51.052378Z","caller":"traceutil/trace.go:171","msg":"trace[606745739] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"300.309081ms","start":"2026-07-02T05:13:50.751203Z","end":"2026-07-02T05:13:51.051513Z","steps":["trace[606745739] 'process raft request' (duration: 300.029824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:51.052499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:50.751177Z","time spent":"301.249147ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:13:51.05333Z","caller":"traceutil/trace.go:171","msg":"trace[586900229] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"119.670754ms","start":"2026-07-02T05:13:50.933624Z","end":"2026-07-02T05:13:51.053295Z","steps":["trace[586900229] 'process raft request' (duration: 119.454938ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:14:09.516089Z","caller":"traceutil/trace.go:171","msg":"trace[508719973] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"127.221771ms","start":"2026-07-02T05:14:09.388815Z","end":"2026-07-02T05:14:09.516037Z","steps":["trace[508719973] 'process raft request' (duration: 65.882207ms)","trace[508719973] 'compare' (duration: 60.962184ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:14:39.728217Z","caller":"traceutil/trace.go:171","msg":"trace[1127569238] transaction","detail":"{read_only:false; response_revision:2349; number_of_response:1; }","duration":"141.137724ms","start":"2026-07-02T05:14:39.586966Z","end":"2026-07-02T05:14:39.728104Z","steps":["trace[1127569238] 'process raft request' (duration: 140.928158ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:14:45.686776Z","caller":"traceutil/trace.go:171","msg":"trace[1087955817] transaction","detail":"{read_only:false; response_revision:2375; number_of_response:1; }","duration":"115.484095ms","start":"2026-07-02T05:14:45.571259Z","end":"2026-07-02T05:14:45.686743Z","steps":["trace[1087955817] 'process raft request' (duration: 89.164374ms)","trace[1087955817] 'compare' (duration: 26.044195ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:14:45.686895Z","caller":"traceutil/trace.go:171","msg":"trace[1449726433] transaction","detail":"{read_only:false; response_revision:2376; number_of_response:1; }","duration":"115.523287ms","start":"2026-07-02T05:14:45.571314Z","end":"2026-07-02T05:14:45.686837Z","steps":["trace[1449726433] 'process raft request' (duration: 115.302991ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:14:58.557154Z","caller":"traceutil/trace.go:171","msg":"trace[1886115159] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"129.207965ms","start":"2026-07-02T05:14:58.427858Z","end":"2026-07-02T05:14:58.557065Z","steps":["trace[1886115159] 'process raft request' (duration: 123.024293ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:15:05.35217Z","caller":"traceutil/trace.go:171","msg":"trace[1001252453] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"534.216771ms","start":"2026-07-02T05:15:04.817915Z","end":"2026-07-02T05:15:05.352132Z","steps":["trace[1001252453] 'process raft request' (duration: 534.017637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:15:05.352397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:15:04.817879Z","time spent":"534.391356ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:15:14.085771Z","caller":"traceutil/trace.go:171","msg":"trace[1209788045] linearizableReadLoop","detail":"{readStateIndex:2638; appliedIndex:2636; }","duration":"134.560007ms","start":"2026-07-02T05:15:13.951182Z","end":"2026-07-02T05:15:14.085742Z","steps":["trace[1209788045] 'read index received' (duration: 98.295721ms)","trace[1209788045] 'applied index is now lower than readState.Index' (duration: 36.262816ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:15:14.08637Z","caller":"traceutil/trace.go:171","msg":"trace[1198006520] transaction","detail":"{read_only:false; response_revision:2531; number_of_response:1; }","duration":"161.660177ms","start":"2026-07-02T05:15:13.924647Z","end":"2026-07-02T05:15:14.086307Z","steps":["trace[1198006520] 'process raft request' (duration: 161.030482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:15:14.086456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.249294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-02T05:15:14.08651Z","caller":"traceutil/trace.go:171","msg":"trace[1224236756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2531; }","duration":"135.375668ms","start":"2026-07-02T05:15:13.951121Z","end":"2026-07-02T05:15:14.086496Z","steps":["trace[1224236756] 'agreement among raft nodes before linearized reading' (duration: 134.959237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:15:14.086506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.38075ms","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":"warn","ts":"2026-07-02T05:15:14.086573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.849771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-02T05:15:14.086625Z","caller":"traceutil/trace.go:171","msg":"trace[1542364198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2531; }","duration":"112.549984ms","start":"2026-07-02T05:15:13.974052Z","end":"2026-07-02T05:15:14.086602Z","steps":["trace[1542364198] 'agreement among raft nodes before linearized reading' (duration: 112.166644ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:15:14.086652Z","caller":"traceutil/trace.go:171","msg":"trace[1114697963] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2531; }","duration":"133.978263ms","start":"2026-07-02T05:15:13.952652Z","end":"2026-07-02T05:15:14.08663Z","steps":["trace[1114697963] 'agreement among raft nodes before linearized reading' (duration: 133.599533ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:15:14.086853Z","caller":"traceutil/trace.go:171","msg":"trace[226516683] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"162.609251ms","start":"2026-07-02T05:15:13.92422Z","end":"2026-07-02T05:15:14.086829Z","steps":["trace[226516683] 'process raft request' (duration: 125.097273ms)","trace[226516683] 'compare' (duration: 36.094912ms)"],"step_count":2}