{"level":"warn","ts":"2026-06-03T07:31:47.885086Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T07:31:47.885228Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.88:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.88:2380","--initial-cluster=instance=https://199.19.213.88:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.88:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.88:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-03T07:31:47.88532Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T07:31:47.885337Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.88:2380"]} {"level":"info","ts":"2026-06-03T07:31:47.885368Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-03T07:31:47.886365Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.88:2379"]} {"level":"info","ts":"2026-06-03T07:31:47.886473Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.88:2380"],"listen-peer-urls":["https://199.19.213.88:2380"],"advertise-client-urls":["https://199.19.213.88:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.88:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.88:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-03T07:31:47.893623Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.884268ms"} {"level":"info","ts":"2026-06-03T07:31:47.904Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f209534991425602","cluster-id":"7e9462d56ed655d"} {"level":"info","ts":"2026-06-03T07:31:47.904066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 switched to configuration voters=()"} {"level":"info","ts":"2026-06-03T07:31:47.904088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 became follower at term 0"} {"level":"info","ts":"2026-06-03T07:31:47.904095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f209534991425602 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-03T07:31:47.904102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 became follower at term 1"} {"level":"info","ts":"2026-06-03T07:31:47.904134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 switched to configuration voters=(17440562607403718146)"} {"level":"warn","ts":"2026-06-03T07:31:47.910692Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-03T07:31:47.911937Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-03T07:31:47.918579Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-03T07:31:47.921643Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f209534991425602","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-03T07:31:47.922438Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f209534991425602","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-03T07:31:47.923038Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-03T07:31:47.923307Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-03T07:31:47.923346Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-03T07:31:47.923702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 switched to configuration voters=(17440562607403718146)"} {"level":"info","ts":"2026-06-03T07:31:47.923994Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7e9462d56ed655d","local-member-id":"f209534991425602","added-peer-id":"f209534991425602","added-peer-peer-urls":["https://199.19.213.88:2380"]} {"level":"info","ts":"2026-06-03T07:31:47.92674Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-03T07:31:47.926896Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.88:2380"} {"level":"info","ts":"2026-06-03T07:31:47.926976Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.88:2380"} {"level":"info","ts":"2026-06-03T07:31:47.927561Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f209534991425602","initial-advertise-peer-urls":["https://199.19.213.88:2380"],"listen-peer-urls":["https://199.19.213.88:2380"],"advertise-client-urls":["https://199.19.213.88:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.88:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-03T07:31:47.927587Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-03T07:31:48.505202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 is starting a new election at term 1"} {"level":"info","ts":"2026-06-03T07:31:48.505274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-03T07:31:48.505313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 received MsgPreVoteResp from f209534991425602 at term 1"} {"level":"info","ts":"2026-06-03T07:31:48.505379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 became candidate at term 2"} {"level":"info","ts":"2026-06-03T07:31:48.505395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 received MsgVoteResp from f209534991425602 at term 2"} {"level":"info","ts":"2026-06-03T07:31:48.505406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 became leader at term 2"} {"level":"info","ts":"2026-06-03T07:31:48.505418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f209534991425602 elected leader f209534991425602 at term 2"} {"level":"info","ts":"2026-06-03T07:31:48.506582Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f209534991425602","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.88:2379]}","request-path":"/0/members/f209534991425602/attributes","cluster-id":"7e9462d56ed655d","publish-timeout":"7s"} {"level":"info","ts":"2026-06-03T07:31:48.506627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T07:31:48.50703Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T07:31:48.507188Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T07:31:48.507365Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-03T07:31:48.507446Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-03T07:31:48.507899Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7e9462d56ed655d","local-member-id":"f209534991425602","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T07:31:48.508041Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T07:31:48.50808Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T07:31:48.511252Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-03T07:31:48.513118Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.88:2379"} {"level":"info","ts":"2026-06-03T07:31:55.335729Z","caller":"traceutil/trace.go:171","msg":"trace[708502499] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:334; }","duration":"203.943577ms","start":"2026-06-03T07:31:55.131761Z","end":"2026-06-03T07:31:55.335705Z","steps":["trace[708502499] 'read index received' (duration: 203.720292ms)","trace[708502499] 'applied index is now lower than readState.Index' (duration: 222.615µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:31:55.335867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.085801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-03T07:31:55.33597Z","caller":"traceutil/trace.go:171","msg":"trace[1154145259] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:322; }","duration":"204.227314ms","start":"2026-06-03T07:31:55.131682Z","end":"2026-06-03T07:31:55.33591Z","steps":["trace[1154145259] 'agreement among raft nodes before linearized reading' (duration: 204.130862ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:55.335998Z","caller":"traceutil/trace.go:171","msg":"trace[917700953] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"241.199119ms","start":"2026-06-03T07:31:55.094661Z","end":"2026-06-03T07:31:55.33586Z","steps":["trace[917700953] 'process raft request' (duration: 240.890091ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:21.73257Z","caller":"traceutil/trace.go:171","msg":"trace[2078921439] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"161.405278ms","start":"2026-06-03T07:32:21.571138Z","end":"2026-06-03T07:32:21.732544Z","steps":["trace[2078921439] 'read index received' (duration: 161.231384ms)","trace[2078921439] 'applied index is now lower than readState.Index' (duration: 172.564µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:21.732719Z","caller":"traceutil/trace.go:171","msg":"trace[1176883997] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"279.457417ms","start":"2026-06-03T07:32:21.45324Z","end":"2026-06-03T07:32:21.732697Z","steps":["trace[1176883997] 'process raft request' (duration: 279.153919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:21.733017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.062701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T07:32:21.733045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.894971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.88\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-03T07:32:21.733112Z","caller":"traceutil/trace.go:171","msg":"trace[1963243796] range","detail":"{range_begin:/registry/masterleases/199.19.213.88; range_end:; response_count:1; response_revision:485; }","duration":"162.014374ms","start":"2026-06-03T07:32:21.571083Z","end":"2026-06-03T07:32:21.733097Z","steps":["trace[1963243796] 'agreement among raft nodes before linearized reading' (duration: 161.888771ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:21.733111Z","caller":"traceutil/trace.go:171","msg":"trace[239313439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"115.192995ms","start":"2026-06-03T07:32:21.617888Z","end":"2026-06-03T07:32:21.733081Z","steps":["trace[239313439] 'agreement among raft nodes before linearized reading' (duration: 115.065172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:24.919822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6197690363046937758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T07:32:24.959872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.015002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:32:24.959986Z","caller":"traceutil/trace.go:171","msg":"trace[1514039092] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"598.241981ms","start":"2026-06-03T07:32:24.361729Z","end":"2026-06-03T07:32:24.959971Z","steps":["trace[1514039092] 'compare' (duration: 588.846107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:24.960053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:24.361715Z","time spent":"598.307242ms","remote":"127.0.0.1:48960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:32:24.960647Z","caller":"traceutil/trace.go:171","msg":"trace[761533705] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"598.782604ms","start":"2026-06-03T07:32:24.361821Z","end":"2026-06-03T07:32:24.960604Z","steps":["trace[761533705] 'process raft request' (duration: 598.474776ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:24.960692Z","caller":"traceutil/trace.go:171","msg":"trace[707660521] transaction","detail":"{read_only:false; number_of_response:1; response_revision:553; }","duration":"597.640815ms","start":"2026-06-03T07:32:24.363024Z","end":"2026-06-03T07:32:24.960665Z","steps":["trace[707660521] 'process raft request' (duration: 597.46099ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:24.960755Z","caller":"traceutil/trace.go:171","msg":"trace[364011441] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"592.719898ms","start":"2026-06-03T07:32:24.36795Z","end":"2026-06-03T07:32:24.96067Z","steps":["trace[364011441] 'process raft request' (duration: 592.620925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:24.960896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:24.361807Z","time spent":"598.910508ms","remote":"127.0.0.1:48960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:32:24.961068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:24.367935Z","time spent":"592.975454ms","remote":"127.0.0.1:48960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:32:24.961097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:24.362994Z","time spent":"597.847759ms","remote":"127.0.0.1:48960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":96,"response count":0,"response size":30444,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:32:24.968814Z","caller":"traceutil/trace.go:171","msg":"trace[704885165] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:578; }","duration":"549.561803ms","start":"2026-06-03T07:32:24.419233Z","end":"2026-06-03T07:32:24.968795Z","steps":["trace[704885165] 'read index received' (duration: 541.151866ms)","trace[704885165] 'applied index is now lower than readState.Index' (duration: 8.407377ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:24.968899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.666345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-03T07:32:24.96893Z","caller":"traceutil/trace.go:171","msg":"trace[1012691613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"549.734347ms","start":"2026-06-03T07:32:24.419186Z","end":"2026-06-03T07:32:24.968921Z","steps":["trace[1012691613] 'agreement among raft nodes before linearized reading' (duration: 549.664825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:24.968953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:24.419172Z","time spent":"549.775909ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-03T07:32:24.969071Z","caller":"traceutil/trace.go:171","msg":"trace[2137021209] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"584.011652ms","start":"2026-06-03T07:32:24.385032Z","end":"2026-06-03T07:32:24.969044Z","steps":["trace[2137021209] 'process raft request' (duration: 583.53522ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:24.969099Z","caller":"traceutil/trace.go:171","msg":"trace[920906537] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"499.408037ms","start":"2026-06-03T07:32:24.469672Z","end":"2026-06-03T07:32:24.96908Z","steps":["trace[920906537] 'process raft request' (duration: 499.072158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:24.969207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:24.385011Z","time spent":"584.121085ms","remote":"127.0.0.1:48960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:32:24.969298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:24.469647Z","time spent":"499.610242ms","remote":"127.0.0.1:48960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T07:32:31.363214Z","caller":"traceutil/trace.go:171","msg":"trace[1726317194] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"324.831918ms","start":"2026-06-03T07:32:31.038354Z","end":"2026-06-03T07:32:31.363186Z","steps":["trace[1726317194] 'read index received' (duration: 323.927195ms)","trace[1726317194] 'applied index is now lower than readState.Index' (duration: 903.753µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:31.363335Z","caller":"traceutil/trace.go:171","msg":"trace[1763895998] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"477.574344ms","start":"2026-06-03T07:32:30.885748Z","end":"2026-06-03T07:32:31.363323Z","steps":["trace[1763895998] 'process raft request' (duration: 476.831274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:31.363459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:30.885724Z","time spent":"477.638736ms","remote":"127.0.0.1:49012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:32:31.363662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.281321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-03T07:32:31.363783Z","caller":"traceutil/trace.go:171","msg":"trace[1395609691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"325.415024ms","start":"2026-06-03T07:32:31.038337Z","end":"2026-06-03T07:32:31.363752Z","steps":["trace[1395609691] 'agreement among raft nodes before linearized reading' (duration: 325.087186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:31.363853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:31.038273Z","time spent":"325.564797ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-03T07:32:31.364139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.142702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-svj2x\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-03T07:32:31.364867Z","caller":"traceutil/trace.go:171","msg":"trace[331668876] range","detail":"{range_begin:/registry/pods/kube-system/cilium-svj2x; range_end:; response_count:1; response_revision:633; }","duration":"150.237781ms","start":"2026-06-03T07:32:31.214591Z","end":"2026-06-03T07:32:31.364828Z","steps":["trace[331668876] 'agreement among raft nodes before linearized reading' (duration: 148.777253ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:31.507433Z","caller":"traceutil/trace.go:171","msg":"trace[1765431269] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"136.438073ms","start":"2026-06-03T07:32:31.370946Z","end":"2026-06-03T07:32:31.507384Z","steps":["trace[1765431269] 'process raft request' (duration: 136.202107ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:31.556246Z","caller":"traceutil/trace.go:171","msg":"trace[202999804] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"121.889317ms","start":"2026-06-03T07:32:31.434332Z","end":"2026-06-03T07:32:31.556221Z","steps":["trace[202999804] 'process raft request' (duration: 121.832736ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:31.55633Z","caller":"traceutil/trace.go:171","msg":"trace[1175039722] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"178.478058ms","start":"2026-06-03T07:32:31.377798Z","end":"2026-06-03T07:32:31.556276Z","steps":["trace[1175039722] 'process raft request' (duration: 178.223052ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:32.060187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.681376ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-06-03T07:32:32.060328Z","caller":"traceutil/trace.go:171","msg":"trace[461419620] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"436.689338ms","start":"2026-06-03T07:32:31.623573Z","end":"2026-06-03T07:32:32.060262Z","steps":["trace[461419620] 'read index received' (duration: 188.833007ms)","trace[461419620] 'applied index is now lower than readState.Index' (duration: 247.851381ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:32.060341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:31.572745Z","time spent":"487.589902ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-03T07:32:32.060425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.849942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:32:32.060457Z","caller":"traceutil/trace.go:171","msg":"trace[962298505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"436.920414ms","start":"2026-06-03T07:32:31.623527Z","end":"2026-06-03T07:32:32.060448Z","steps":["trace[962298505] 'agreement among raft nodes before linearized reading' (duration: 436.857682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:32.060481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:31.623505Z","time spent":"436.969816ms","remote":"127.0.0.1:48902","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-06-03T07:32:32.086345Z","caller":"traceutil/trace.go:171","msg":"trace[658291339] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"121.119118ms","start":"2026-06-03T07:32:31.9652Z","end":"2026-06-03T07:32:32.086319Z","steps":["trace[658291339] 'process raft request' (duration: 120.237605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:32.086492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.86171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-03T07:32:32.086722Z","caller":"traceutil/trace.go:171","msg":"trace[848959229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"234.126717ms","start":"2026-06-03T07:32:31.852559Z","end":"2026-06-03T07:32:32.086685Z","steps":["trace[848959229] 'agreement among raft nodes before linearized reading' (duration: 233.777178ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:33.771872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375191214s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:32:33.771997Z","caller":"traceutil/trace.go:171","msg":"trace[1760655283] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"1.626840602s","start":"2026-06-03T07:32:32.145111Z","end":"2026-06-03T07:32:33.771952Z","steps":["trace[1760655283] 'read index received' (duration: 251.497424ms)","trace[1760655283] 'applied index is now lower than readState.Index' (duration: 1.375342118s)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:33.772073Z","caller":"traceutil/trace.go:171","msg":"trace[1176306170] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.635361852s","start":"2026-06-03T07:32:32.136703Z","end":"2026-06-03T07:32:33.772065Z","steps":["trace[1176306170] 'process raft request' (duration: 259.909451ms)","trace[1176306170] 'compare' (duration: 1.374135207s)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:33.772113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:32.136676Z","time spent":"1.635420334s","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:32:33.772384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583897454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-03T07:32:33.772439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.546205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:32:33.77247Z","caller":"traceutil/trace.go:171","msg":"trace[113814600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.584005848s","start":"2026-06-03T07:32:32.188441Z","end":"2026-06-03T07:32:33.772447Z","steps":["trace[113814600] 'agreement among raft nodes before linearized reading' (duration: 1.58374339s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:33.772487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.225311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-03T07:32:33.772516Z","caller":"traceutil/trace.go:171","msg":"trace[96381452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"260.288152ms","start":"2026-06-03T07:32:33.512221Z","end":"2026-06-03T07:32:33.772509Z","steps":["trace[96381452] 'agreement among raft nodes before linearized reading' (duration: 260.18619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:33.772534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:32.188428Z","time spent":"1.58408892s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-03T07:32:33.772482Z","caller":"traceutil/trace.go:171","msg":"trace[470330261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"153.71805ms","start":"2026-06-03T07:32:33.618748Z","end":"2026-06-03T07:32:33.772466Z","steps":["trace[470330261] 'agreement among raft nodes before linearized reading' (duration: 153.504034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:33.772752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.560639923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-03T07:32:33.772795Z","caller":"traceutil/trace.go:171","msg":"trace[683639338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.560716865s","start":"2026-06-03T07:32:32.212064Z","end":"2026-06-03T07:32:33.772781Z","steps":["trace[683639338] 'agreement among raft nodes before linearized reading' (duration: 1.560600402s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:33.772803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627670124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-03T07:32:33.772839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:32.212044Z","time spent":"1.560787327s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-03T07:32:33.772881Z","caller":"traceutil/trace.go:171","msg":"trace[1995918770] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:640; }","duration":"1.627781327s","start":"2026-06-03T07:32:32.145081Z","end":"2026-06-03T07:32:33.772862Z","steps":["trace[1995918770] 'agreement among raft nodes before linearized reading' (duration: 1.627540021s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:33.772927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:32.145068Z","time spent":"1.627849279s","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-06-03T07:32:35.642623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.999223ms","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-06-03T07:32:35.642735Z","caller":"traceutil/trace.go:171","msg":"trace[797352033] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:681; }","duration":"119.150967ms","start":"2026-06-03T07:32:35.523554Z","end":"2026-06-03T07:32:35.642705Z","steps":["trace[797352033] 'range keys from in-memory index tree' (duration: 118.924841ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:38.560142Z","caller":"traceutil/trace.go:171","msg":"trace[1929810252] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"136.053734ms","start":"2026-06-03T07:32:38.424027Z","end":"2026-06-03T07:32:38.560081Z","steps":["trace[1929810252] 'process raft request' (duration: 132.098261ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:49.739885Z","caller":"traceutil/trace.go:171","msg":"trace[24034540] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"744.510122ms","start":"2026-06-03T07:32:48.995351Z","end":"2026-06-03T07:32:49.739861Z","steps":["trace[24034540] 'process raft request' (duration: 744.372868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:49.740039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:48.995323Z","time spent":"744.640285ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:32:49.756692Z","caller":"traceutil/trace.go:171","msg":"trace[1646793434] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"427.629828ms","start":"2026-06-03T07:32:49.329041Z","end":"2026-06-03T07:32:49.756671Z","steps":["trace[1646793434] 'process raft request' (duration: 427.512285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:49.756841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:49.329016Z","time spent":"427.739571ms","remote":"127.0.0.1:49154","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-06-03T07:33:24.312837Z","caller":"traceutil/trace.go:171","msg":"trace[1933416076] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"212.615664ms","start":"2026-06-03T07:33:24.100197Z","end":"2026-06-03T07:33:24.312813Z","steps":["trace[1933416076] 'process raft request' (duration: 212.559153ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:24.312951Z","caller":"traceutil/trace.go:171","msg":"trace[1083972519] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"212.721136ms","start":"2026-06-03T07:33:24.100173Z","end":"2026-06-03T07:33:24.312894Z","steps":["trace[1083972519] 'process raft request' (duration: 212.46781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:24.903105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6197690363046938756,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T07:33:25.403582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6197690363046938756,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T07:33:25.62326Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.310586641s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-03T07:33:25.891826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.070783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:33:25.891932Z","caller":"traceutil/trace.go:171","msg":"trace[829975477] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.791495412s","start":"2026-06-03T07:33:24.100415Z","end":"2026-06-03T07:33:25.89191Z","steps":["trace[829975477] 'process raft request' (duration: 1.523084281s)","trace[829975477] 'compare' (duration: 267.979861ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:33:25.891997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:24.100408Z","time spent":"1.791558965s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:33:26.480328Z","caller":"traceutil/trace.go:171","msg":"trace[1389773242] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"1.98258236s","start":"2026-06-03T07:33:24.49772Z","end":"2026-06-03T07:33:26.480303Z","steps":["trace[1389773242] 'process raft request' (duration: 1.980646671s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:26.480475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:24.497691Z","time spent":"1.982711834s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:33:26.480567Z","caller":"traceutil/trace.go:171","msg":"trace[553156272] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1059; }","duration":"2.077837312s","start":"2026-06-03T07:33:24.402686Z","end":"2026-06-03T07:33:26.480523Z","steps":["trace[553156272] 'read index received' (duration: 1.22082338s)","trace[553156272] 'applied index is now lower than readState.Index' (duration: 857.012082ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:26.480618Z","caller":"traceutil/trace.go:171","msg":"trace[612451567] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"1.367358286s","start":"2026-06-03T07:33:25.113209Z","end":"2026-06-03T07:33:26.480567Z","steps":["trace[612451567] 'process raft request' (duration: 1.367102828s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:26.48078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.861567946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:33:26.480829Z","caller":"traceutil/trace.go:171","msg":"trace[2057763038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"1.861645148s","start":"2026-06-03T07:33:24.619173Z","end":"2026-06-03T07:33:26.480818Z","steps":["trace[2057763038] 'agreement among raft nodes before linearized reading' (duration: 1.861571896s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:26.480837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:25.11319Z","time spent":"1.367522119s","remote":"127.0.0.1:49178","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-06-03T07:33:26.480901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.053575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-03T07:33:26.480865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:24.619152Z","time spent":"1.861704489s","remote":"127.0.0.1:48902","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-06-03T07:33:26.481036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.599927175s","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-06-03T07:33:26.481069Z","caller":"traceutil/trace.go:171","msg":"trace[653553750] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1020; }","duration":"1.599984686s","start":"2026-06-03T07:33:24.881075Z","end":"2026-06-03T07:33:26.481059Z","steps":["trace[653553750] 'agreement among raft nodes before linearized reading' (duration: 1.599919174s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:26.481078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.078394706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-03T07:33:26.481099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:24.881055Z","time spent":"1.600038208s","remote":"127.0.0.1:49252","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":"info","ts":"2026-06-03T07:33:26.481124Z","caller":"traceutil/trace.go:171","msg":"trace[801770809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"2.078478588s","start":"2026-06-03T07:33:24.402633Z","end":"2026-06-03T07:33:26.481111Z","steps":["trace[801770809] 'agreement among raft nodes before linearized reading' (duration: 2.078359685s)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:26.480966Z","caller":"traceutil/trace.go:171","msg":"trace[530107474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"165.163778ms","start":"2026-06-03T07:33:26.315786Z","end":"2026-06-03T07:33:26.48095Z","steps":["trace[530107474] 'agreement among raft nodes before linearized reading' (duration: 164.962663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:26.481191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:24.402611Z","time spent":"2.078561171s","remote":"127.0.0.1:49178","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-06-03T07:33:26.481231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.715326179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:33:26.481252Z","caller":"traceutil/trace.go:171","msg":"trace[1443379853] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1020; }","duration":"1.715379741s","start":"2026-06-03T07:33:24.765866Z","end":"2026-06-03T07:33:26.481246Z","steps":["trace[1443379853] 'agreement among raft nodes before linearized reading' (duration: 1.71534474s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:26.481272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:24.765826Z","time spent":"1.715441252s","remote":"127.0.0.1:49168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-06-03T07:33:26.480977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.864769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-03T07:33:26.481454Z","caller":"traceutil/trace.go:171","msg":"trace[1418273251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"484.382552ms","start":"2026-06-03T07:33:25.997055Z","end":"2026-06-03T07:33:26.481437Z","steps":["trace[1418273251] 'agreement among raft nodes before linearized reading' (duration: 483.829769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:26.481538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:25.997026Z","time spent":"484.496785ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T07:33:26.481565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.807284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-03T07:33:26.481643Z","caller":"traceutil/trace.go:171","msg":"trace[1441727946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"163.899217ms","start":"2026-06-03T07:33:26.317709Z","end":"2026-06-03T07:33:26.481608Z","steps":["trace[1441727946] 'agreement among raft nodes before linearized reading' (duration: 163.62558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:48.572697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.721099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-03T07:33:48.572777Z","caller":"traceutil/trace.go:171","msg":"trace[1300439383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"586.852552ms","start":"2026-06-03T07:33:47.985905Z","end":"2026-06-03T07:33:48.572758Z","steps":["trace[1300439383] 'range keys from in-memory index tree' (duration: 586.537604ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:48.572817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:47.985884Z","time spent":"586.924243ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-03T07:33:48.572951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.718892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T07:33:48.572998Z","caller":"traceutil/trace.go:171","msg":"trace[1330641639] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1090; }","duration":"847.771874ms","start":"2026-06-03T07:33:47.725213Z","end":"2026-06-03T07:33:48.572985Z","steps":["trace[1330641639] 'count revisions from in-memory index tree' (duration: 847.63946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:48.573032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:47.725153Z","time spent":"847.870687ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-06-03T07:33:48.57308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.470296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:33:48.573178Z","caller":"traceutil/trace.go:171","msg":"trace[1916045289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"954.609069ms","start":"2026-06-03T07:33:47.618544Z","end":"2026-06-03T07:33:48.573153Z","steps":["trace[1916045289] 'range keys from in-memory index tree' (duration: 954.304872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:48.573234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:47.618529Z","time spent":"954.690042ms","remote":"127.0.0.1:48902","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-06-03T07:33:48.724969Z","caller":"traceutil/trace.go:171","msg":"trace[1990617219] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1140; }","duration":"105.512372ms","start":"2026-06-03T07:33:48.619431Z","end":"2026-06-03T07:33:48.724943Z","steps":["trace[1990617219] 'read index received' (duration: 4.867854ms)","trace[1990617219] 'applied index is now lower than readState.Index' (duration: 100.642968ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:48.725044Z","caller":"traceutil/trace.go:171","msg":"trace[1917052907] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"108.296884ms","start":"2026-06-03T07:33:48.616693Z","end":"2026-06-03T07:33:48.72499Z","steps":["trace[1917052907] 'process raft request' (duration: 69.977966ms)","trace[1917052907] 'compare' (duration: 38.081402ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:48.72513Z","caller":"traceutil/trace.go:171","msg":"trace[1051460783] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"108.230482ms","start":"2026-06-03T07:33:48.616877Z","end":"2026-06-03T07:33:48.725107Z","steps":["trace[1051460783] 'process raft request' (duration: 108.008556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:48.725385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.890282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:33:48.725518Z","caller":"traceutil/trace.go:171","msg":"trace[1568248801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"106.026826ms","start":"2026-06-03T07:33:48.619407Z","end":"2026-06-03T07:33:48.725434Z","steps":["trace[1568248801] 'agreement among raft nodes before linearized reading' (duration: 105.835001ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:52.704122Z","caller":"traceutil/trace.go:171","msg":"trace[1007281075] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"100.78102ms","start":"2026-06-03T07:33:52.603289Z","end":"2026-06-03T07:33:52.70407Z","steps":["trace[1007281075] 'process raft request' (duration: 100.549304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:07.26789Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6197690363046939211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T07:34:08.358707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.462353ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-03T07:34:08.374595Z","caller":"traceutil/trace.go:171","msg":"trace[1581546442] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"1.619151308s","start":"2026-06-03T07:34:06.755419Z","end":"2026-06-03T07:34:08.374571Z","steps":["trace[1581546442] 'process raft request' (duration: 1.618975583s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:08.374755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:06.755396Z","time spent":"1.61927218s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:34:08.374851Z","caller":"traceutil/trace.go:171","msg":"trace[716911271] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1253; }","duration":"1.6079071s","start":"2026-06-03T07:34:06.766922Z","end":"2026-06-03T07:34:08.374829Z","steps":["trace[716911271] 'read index received' (duration: 829.4172ms)","trace[716911271] 'applied index is now lower than readState.Index' (duration: 778.48822ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:34:08.375001Z","caller":"traceutil/trace.go:171","msg":"trace[1158913453] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"925.812299ms","start":"2026-06-03T07:34:07.449178Z","end":"2026-06-03T07:34:08.374991Z","steps":["trace[1158913453] 'process raft request' (duration: 925.349158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:08.375116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585962131s","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-06-03T07:34:08.375145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:07.449152Z","time spent":"925.873831ms","remote":"127.0.0.1:49178","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-06-03T07:34:08.375157Z","caller":"traceutil/trace.go:171","msg":"trace[2144242951] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1203; }","duration":"1.586031783s","start":"2026-06-03T07:34:06.789114Z","end":"2026-06-03T07:34:08.375146Z","steps":["trace[2144242951] 'agreement among raft nodes before linearized reading' (duration: 1.58590755s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:08.375196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:06.789099Z","time spent":"1.586087135s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T07:34:08.375384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541825726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-03T07:34:08.375391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608467476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-03T07:34:08.375374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539349882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-03T07:34:08.375412Z","caller":"traceutil/trace.go:171","msg":"trace[1094663281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1203; }","duration":"1.541884737s","start":"2026-06-03T07:34:06.833519Z","end":"2026-06-03T07:34:08.375404Z","steps":["trace[1094663281] 'agreement among raft nodes before linearized reading' (duration: 1.541806025s)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:08.375424Z","caller":"traceutil/trace.go:171","msg":"trace[1549273401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1203; }","duration":"1.608520857s","start":"2026-06-03T07:34:06.766888Z","end":"2026-06-03T07:34:08.375409Z","steps":["trace[1549273401] 'agreement among raft nodes before linearized reading' (duration: 1.608386973s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:08.375442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:06.833502Z","time spent":"1.541934509s","remote":"127.0.0.1:49178","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-06-03T07:34:08.375458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:06.766876Z","time spent":"1.608577009s","remote":"127.0.0.1:49178","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-06-03T07:34:08.375446Z","caller":"traceutil/trace.go:171","msg":"trace[576937247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1203; }","duration":"1.539446724s","start":"2026-06-03T07:34:06.835979Z","end":"2026-06-03T07:34:08.375425Z","steps":["trace[576937247] 'agreement among raft nodes before linearized reading' (duration: 1.53926492s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:08.375498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.11807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T07:34:08.375534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:06.835969Z","time spent":"1.539558188s","remote":"127.0.0.1:49178","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":"info","ts":"2026-06-03T07:34:08.375543Z","caller":"traceutil/trace.go:171","msg":"trace[777679720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"756.199762ms","start":"2026-06-03T07:34:07.619334Z","end":"2026-06-03T07:34:08.375534Z","steps":["trace[777679720] 'agreement among raft nodes before linearized reading' (duration: 756.142251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:08.37558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:07.619289Z","time spent":"756.279724ms","remote":"127.0.0.1:48902","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-06-03T07:34:08.37564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583520889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-03T07:34:08.375669Z","caller":"traceutil/trace.go:171","msg":"trace[621729178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1203; }","duration":"1.583578781s","start":"2026-06-03T07:34:06.792082Z","end":"2026-06-03T07:34:08.375661Z","steps":["trace[621729178] 'agreement among raft nodes before linearized reading' (duration: 1.583489719s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:08.375695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:06.792073Z","time spent":"1.583615852s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-03T07:34:23.020306Z","caller":"traceutil/trace.go:171","msg":"trace[497512236] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"187.575055ms","start":"2026-06-03T07:34:22.832696Z","end":"2026-06-03T07:34:23.020271Z","steps":["trace[497512236] 'process raft request' (duration: 187.410251ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:26.442379Z","caller":"traceutil/trace.go:171","msg":"trace[1137137478] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"688.247619ms","start":"2026-06-03T07:34:25.754112Z","end":"2026-06-03T07:34:26.442359Z","steps":["trace[1137137478] 'process raft request' (duration: 688.103695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:26.442519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:25.754092Z","time spent":"688.357511ms","remote":"127.0.0.1:49094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5639,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:34:28.785504Z","caller":"traceutil/trace.go:171","msg":"trace[1978912204] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"233.284802ms","start":"2026-06-03T07:34:28.552177Z","end":"2026-06-03T07:34:28.785462Z","steps":["trace[1978912204] 'read index received' (duration: 233.128488ms)","trace[1978912204] 'applied index is now lower than readState.Index' (duration: 155.434µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:34:28.785607Z","caller":"traceutil/trace.go:171","msg":"trace[855401194] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"253.391904ms","start":"2026-06-03T07:34:28.532202Z","end":"2026-06-03T07:34:28.785594Z","steps":["trace[855401194] 'process raft request' (duration: 253.120087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:28.78585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.665091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-03T07:34:28.78589Z","caller":"traceutil/trace.go:171","msg":"trace[1968594065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1290; }","duration":"233.747594ms","start":"2026-06-03T07:34:28.552131Z","end":"2026-06-03T07:34:28.785878Z","steps":["trace[1968594065] 'agreement among raft nodes before linearized reading' (duration: 233.575449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:28.838005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.120417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-03T07:34:28.838041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.349252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-03T07:34:28.838066Z","caller":"traceutil/trace.go:171","msg":"trace[218579511] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1292; }","duration":"173.22109ms","start":"2026-06-03T07:34:28.664831Z","end":"2026-06-03T07:34:28.838052Z","steps":["trace[218579511] 'agreement among raft nodes before linearized reading' (duration: 173.114367ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:28.838087Z","caller":"traceutil/trace.go:171","msg":"trace[926568435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1292; }","duration":"220.435184ms","start":"2026-06-03T07:34:28.617641Z","end":"2026-06-03T07:34:28.838076Z","steps":["trace[926568435] 'agreement among raft nodes before linearized reading' (duration: 220.286831ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:28.838262Z","caller":"traceutil/trace.go:171","msg":"trace[1231093946] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"217.492078ms","start":"2026-06-03T07:34:28.620757Z","end":"2026-06-03T07:34:28.838249Z","steps":["trace[1231093946] 'process raft request' (duration: 188.318034ms)","trace[1231093946] 'compare' (duration: 28.704712ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:34:28.838334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.593782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-03T07:34:28.838363Z","caller":"traceutil/trace.go:171","msg":"trace[5355294] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"219.637282ms","start":"2026-06-03T07:34:28.618717Z","end":"2026-06-03T07:34:28.838355Z","steps":["trace[5355294] 'agreement among raft nodes before linearized reading' (duration: 219.52983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:28.838454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.234535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T07:34:28.838463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.762219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T07:34:28.83854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.139676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-03T07:34:28.838544Z","caller":"traceutil/trace.go:171","msg":"trace[1584649237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"216.869643ms","start":"2026-06-03T07:34:28.621653Z","end":"2026-06-03T07:34:28.838523Z","steps":["trace[1584649237] 'agreement among raft nodes before linearized reading' (duration: 216.717319ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:28.838576Z","caller":"traceutil/trace.go:171","msg":"trace[1029628528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"220.197107ms","start":"2026-06-03T07:34:28.618371Z","end":"2026-06-03T07:34:28.838568Z","steps":["trace[1029628528] 'agreement among raft nodes before linearized reading' (duration: 220.112845ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:28.838491Z","caller":"traceutil/trace.go:171","msg":"trace[96099358] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1292; }","duration":"125.299836ms","start":"2026-06-03T07:34:28.713183Z","end":"2026-06-03T07:34:28.838483Z","steps":["trace[96099358] 'agreement among raft nodes before linearized reading' (duration: 125.243334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:28.838704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.267874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-03T07:34:28.839032Z","caller":"traceutil/trace.go:171","msg":"trace[1686890750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1292; }","duration":"219.818117ms","start":"2026-06-03T07:34:28.619173Z","end":"2026-06-03T07:34:28.838991Z","steps":["trace[1686890750] 'agreement among raft nodes before linearized reading' (duration: 218.804892ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:28.840075Z","caller":"traceutil/trace.go:171","msg":"trace[1088854176] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"206.55753ms","start":"2026-06-03T07:34:28.631787Z","end":"2026-06-03T07:34:28.838344Z","steps":["trace[1088854176] 'process raft request' (duration: 206.085978ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:29.056077Z","caller":"traceutil/trace.go:171","msg":"trace[1552823316] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"113.191827ms","start":"2026-06-03T07:34:28.942845Z","end":"2026-06-03T07:34:29.056037Z","steps":["trace[1552823316] 'process raft request' (duration: 113.036353ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:30.950004Z","caller":"traceutil/trace.go:171","msg":"trace[316214338] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"100.35636ms","start":"2026-06-03T07:34:30.849623Z","end":"2026-06-03T07:34:30.949979Z","steps":["trace[316214338] 'process raft request' (duration: 54.45086ms)","trace[316214338] 'compare' (duration: 45.379017ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:34:37.18497Z","caller":"traceutil/trace.go:171","msg":"trace[1388677041] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"440.751475ms","start":"2026-06-03T07:34:36.744195Z","end":"2026-06-03T07:34:37.184946Z","steps":["trace[1388677041] 'process raft request' (duration: 440.619162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:37.185112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:36.744178Z","time spent":"440.864988ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:34:38.516802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283618787s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:34:38.517193Z","caller":"traceutil/trace.go:171","msg":"trace[17637379] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"1.68827865s","start":"2026-06-03T07:34:36.828887Z","end":"2026-06-03T07:34:38.517166Z","steps":["trace[17637379] 'process raft request' (duration: 404.202511ms)","trace[17637379] 'compare' (duration: 1.283486984s)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:34:38.517392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:36.828864Z","time spent":"1.688450735s","remote":"127.0.0.1:49178","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-06-03T07:34:38.769326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.42166ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-03T07:34:38.769418Z","caller":"traceutil/trace.go:171","msg":"trace[1241400393] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1414; }","duration":"1.797114017s","start":"2026-06-03T07:34:36.972286Z","end":"2026-06-03T07:34:38.7694Z","steps":["trace[1241400393] 'read index received' (duration: 212.519532ms)","trace[1241400393] 'applied index is now lower than readState.Index' (duration: 1.584592675s)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:34:38.769804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.797505626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-03T07:34:38.769808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.735599277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:34:38.769938Z","caller":"traceutil/trace.go:171","msg":"trace[470415318] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1358; }","duration":"1.735762302s","start":"2026-06-03T07:34:37.034161Z","end":"2026-06-03T07:34:38.769923Z","steps":["trace[470415318] 'agreement among raft nodes before linearized reading' (duration: 1.735583298s)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:38.76989Z","caller":"traceutil/trace.go:171","msg":"trace[2123189656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1358; }","duration":"1.79763383s","start":"2026-06-03T07:34:36.972238Z","end":"2026-06-03T07:34:38.769872Z","steps":["trace[2123189656] 'agreement among raft nodes before linearized reading' (duration: 1.797410044s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:38.769957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.785949352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-03T07:34:38.769988Z","caller":"traceutil/trace.go:171","msg":"trace[1704379252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1358; }","duration":"1.785993073s","start":"2026-06-03T07:34:36.983984Z","end":"2026-06-03T07:34:38.769977Z","steps":["trace[1704379252] 'agreement among raft nodes before linearized reading' (duration: 1.785930042s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:38.769829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.795907366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-03T07:34:38.770019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:36.983976Z","time spent":"1.786035515s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T07:34:38.769983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:36.972219Z","time spent":"1.797754223s","remote":"127.0.0.1:49178","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-06-03T07:34:38.770231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.786662631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-03T07:34:38.77026Z","caller":"traceutil/trace.go:171","msg":"trace[935493847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"1.786712942s","start":"2026-06-03T07:34:36.983538Z","end":"2026-06-03T07:34:38.770251Z","steps":["trace[935493847] 'agreement among raft nodes before linearized reading' (duration: 1.786639161s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:38.770283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:36.983529Z","time spent":"1.786748953s","remote":"127.0.0.1:49178","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-06-03T07:34:38.77002Z","caller":"traceutil/trace.go:171","msg":"trace[79609910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1358; }","duration":"1.796134033s","start":"2026-06-03T07:34:36.973876Z","end":"2026-06-03T07:34:38.77001Z","steps":["trace[79609910] 'agreement among raft nodes before linearized reading' (duration: 1.795828735s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:38.770361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:36.973865Z","time spent":"1.796489292s","remote":"127.0.0.1:49178","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-06-03T07:34:38.770454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.467028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-06-03T07:34:38.770568Z","caller":"traceutil/trace.go:171","msg":"trace[1904236022] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1358; }","duration":"382.617652ms","start":"2026-06-03T07:34:38.387934Z","end":"2026-06-03T07:34:38.770551Z","steps":["trace[1904236022] 'agreement among raft nodes before linearized reading' (duration: 382.440197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:38.770609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:38.387911Z","time spent":"382.686973ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-03T07:34:38.770503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.789176875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-03T07:34:38.770695Z","caller":"traceutil/trace.go:171","msg":"trace[1822527144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1358; }","duration":"1.789395901s","start":"2026-06-03T07:34:36.981289Z","end":"2026-06-03T07:34:38.770685Z","steps":["trace[1822527144] 'agreement among raft nodes before linearized reading' (duration: 1.789113414s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:38.769899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737581488s","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:507"} {"level":"warn","ts":"2026-06-03T07:34:38.769986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:37.034152Z","time spent":"1.735821423s","remote":"127.0.0.1:53750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-06-03T07:34:38.77079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:36.981256Z","time spent":"1.789522164s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-03T07:34:38.770823Z","caller":"traceutil/trace.go:171","msg":"trace[408269676] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1358; }","duration":"1.738509863s","start":"2026-06-03T07:34:37.032288Z","end":"2026-06-03T07:34:38.770798Z","steps":["trace[408269676] 'agreement among raft nodes before linearized reading' (duration: 1.737561788s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:38.770921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:37.032275Z","time spent":"1.738615385s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-03T07:34:38.771251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155065277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:34:38.771321Z","caller":"traceutil/trace.go:171","msg":"trace[1584670824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"1.15516093s","start":"2026-06-03T07:34:37.616148Z","end":"2026-06-03T07:34:38.771309Z","steps":["trace[1584670824] 'agreement among raft nodes before linearized reading' (duration: 1.154585356s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:38.771356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:37.616132Z","time spent":"1.155218041s","remote":"127.0.0.1:48902","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-06-03T07:37:35.234452Z","caller":"traceutil/trace.go:171","msg":"trace[735107757] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"120.815352ms","start":"2026-06-03T07:37:35.113616Z","end":"2026-06-03T07:37:35.234431Z","steps":["trace[735107757] 'process raft request' (duration: 27.991717ms)","trace[735107757] 'compare' (duration: 92.65295ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:38:24.050501Z","caller":"traceutil/trace.go:171","msg":"trace[2103048527] transaction","detail":"{read_only:false; response_revision:2415; number_of_response:1; }","duration":"101.399334ms","start":"2026-06-03T07:38:23.949087Z","end":"2026-06-03T07:38:24.050487Z","steps":["trace[2103048527] 'process raft request' (duration: 100.948952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:38:29.704077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.797043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:38:29.704356Z","caller":"traceutil/trace.go:171","msg":"trace[410279384] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"458.318759ms","start":"2026-06-03T07:38:29.246001Z","end":"2026-06-03T07:38:29.70432Z","steps":["trace[410279384] 'process raft request' (duration: 201.178217ms)","trace[410279384] 'compare' (duration: 256.610279ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:38:29.704526Z","caller":"traceutil/trace.go:171","msg":"trace[1017351630] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"247.523249ms","start":"2026-06-03T07:38:29.456977Z","end":"2026-06-03T07:38:29.7045Z","steps":["trace[1017351630] 'process raft request' (duration: 247.339623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:38:29.704538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:38:29.245971Z","time spent":"458.470783ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:38:29.704535Z","caller":"traceutil/trace.go:171","msg":"trace[930327447] linearizableReadLoop","detail":"{readStateIndex:2553; appliedIndex:2552; }","duration":"348.810599ms","start":"2026-06-03T07:38:29.355666Z","end":"2026-06-03T07:38:29.704477Z","steps":["trace[930327447] 'read index received' (duration: 91.559564ms)","trace[930327447] 'applied index is now lower than readState.Index' (duration: 257.246985ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:38:29.704792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.115937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T07:38:29.704874Z","caller":"traceutil/trace.go:171","msg":"trace[703505835] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2448; }","duration":"349.24521ms","start":"2026-06-03T07:38:29.355603Z","end":"2026-06-03T07:38:29.704848Z","steps":["trace[703505835] 'agreement among raft nodes before linearized reading' (duration: 348.983344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:38:29.704908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.662997ms","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":"warn","ts":"2026-06-03T07:38:29.704926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:38:29.355574Z","time spent":"349.335272ms","remote":"127.0.0.1:49176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-06-03T07:38:29.704948Z","caller":"traceutil/trace.go:171","msg":"trace[1602935131] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2448; }","duration":"175.737269ms","start":"2026-06-03T07:38:29.5292Z","end":"2026-06-03T07:38:29.704937Z","steps":["trace[1602935131] 'agreement among raft nodes before linearized reading' (duration: 175.596715ms)"],"step_count":1}