{"level":"warn","ts":"2026-06-02T23:50:29.837576Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T23:50:29.837668Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-02T23:50:29.83775Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T23:50:29.837762Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-06-02T23:50:29.837784Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-02T23:50:29.838664Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-06-02T23:50:29.838758Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-02T23:50:29.84557Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.59608ms"} {"level":"info","ts":"2026-06-02T23:50:29.857052Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-06-02T23:50:29.85717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T23:50:29.857193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-06-02T23:50:29.857204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T23:50:29.857272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-06-02T23:50:29.857332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-06-02T23:50:29.864322Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T23:50:29.865826Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T23:50:29.868373Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-02T23:50:29.871608Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T23:50:29.871791Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T23:50:29.876279Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-02T23:50:29.876338Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-02T23:50:29.876352Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-02T23:50:29.876855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-06-02T23:50:29.877069Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-06-02T23:50:29.878932Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-02T23:50:29.879129Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T23:50:29.879154Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T23:50:29.879637Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-06-02T23:50:29.879662Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-06-02T23:50:29.958566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T23:50:29.958763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T23:50:29.958864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-06-02T23:50:29.958935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-06-02T23:50:29.959079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-06-02T23:50:29.959128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-06-02T23:50:29.959219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-06-02T23:50:29.960581Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T23:50:29.960731Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T23:50:29.961444Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T23:50:29.961523Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T23:50:29.960907Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:50:29.961014Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T23:50:29.964951Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T23:50:29.965344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-06-02T23:50:29.9703Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:50:29.97046Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:50:29.970494Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-06-02T23:50:32.027957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368099989250419,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T23:50:32.14774Z","caller":"traceutil/trace.go:171","msg":"trace[1556069965] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"621.190136ms","start":"2026-06-02T23:50:31.526529Z","end":"2026-06-02T23:50:32.147719Z","steps":["trace[1556069965] 'process raft request' (duration: 621.017311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:32.148206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.526517Z","time spent":"621.282559ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:50:32.155353Z","caller":"traceutil/trace.go:171","msg":"trace[415995848] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:4; }","duration":"628.136326ms","start":"2026-06-02T23:50:31.527185Z","end":"2026-06-02T23:50:32.155321Z","steps":["trace[415995848] 'read index received' (duration: 620.450707ms)","trace[415995848] 'applied index is now lower than readState.Index' (duration: 7.684769ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:50:32.155474Z","caller":"traceutil/trace.go:171","msg":"trace[226107371] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"549.861596ms","start":"2026-06-02T23:50:31.605601Z","end":"2026-06-02T23:50:32.155462Z","steps":["trace[226107371] 'process raft request' (duration: 549.584629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:32.155576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.605579Z","time spent":"549.938459ms","remote":"127.0.0.1:35392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:50:32.155818Z","caller":"traceutil/trace.go:171","msg":"trace[112529068] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"543.662815ms","start":"2026-06-02T23:50:31.612131Z","end":"2026-06-02T23:50:32.155794Z","steps":["trace[112529068] 'process raft request' (duration: 543.12558ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:50:32.155828Z","caller":"traceutil/trace.go:171","msg":"trace[555899610] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"549.547118ms","start":"2026-06-02T23:50:31.606232Z","end":"2026-06-02T23:50:32.155779Z","steps":["trace[555899610] 'process raft request' (duration: 548.979703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:32.155861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.296729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-02T23:50:32.155933Z","caller":"traceutil/trace.go:171","msg":"trace[1791055025] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"628.420473ms","start":"2026-06-02T23:50:31.527499Z","end":"2026-06-02T23:50:32.15592Z","steps":["trace[1791055025] 'agreement among raft nodes before linearized reading' (duration: 628.185177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:32.155932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.612119Z","time spent":"543.769358ms","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T23:50:32.15594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.606221Z","time spent":"549.680771ms","remote":"127.0.0.1:35392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T23:50:32.155973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.527494Z","time spent":"628.469924ms","remote":"127.0.0.1:34962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-06-02T23:50:32.155981Z","caller":"traceutil/trace.go:171","msg":"trace[331809810] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"562.831642ms","start":"2026-06-02T23:50:31.593124Z","end":"2026-06-02T23:50:32.155955Z","steps":["trace[331809810] 'process raft request' (duration: 561.865127ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:50:32.155378Z","caller":"traceutil/trace.go:171","msg":"trace[292856068] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"549.97986ms","start":"2026-06-02T23:50:31.605367Z","end":"2026-06-02T23:50:32.155347Z","steps":["trace[292856068] 'process raft request' (duration: 549.790714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:32.156052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.593103Z","time spent":"562.916885ms","remote":"127.0.0.1:35330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T23:50:32.15609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.605355Z","time spent":"550.702428ms","remote":"127.0.0.1:35392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:50:32.156128Z","caller":"traceutil/trace.go:171","msg":"trace[763152531] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"532.842814ms","start":"2026-06-02T23:50:31.623272Z","end":"2026-06-02T23:50:32.156115Z","steps":["trace[763152531] 'process raft request' (duration: 532.014203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:32.156222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.623245Z","time spent":"532.938847ms","remote":"127.0.0.1:35066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T23:50:32.156348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.298293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-02T23:50:32.156413Z","caller":"traceutil/trace.go:171","msg":"trace[727555] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"551.301104ms","start":"2026-06-02T23:50:31.605101Z","end":"2026-06-02T23:50:32.156402Z","steps":["trace[727555] 'process raft request' (duration: 550.02687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:32.156423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.875939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-02T23:50:32.156473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.280835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-02T23:50:32.156492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.605088Z","time spent":"551.368305ms","remote":"127.0.0.1:35392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T23:50:32.156499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.740032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-02T23:50:32.156496Z","caller":"traceutil/trace.go:171","msg":"trace[381772847] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"573.980471ms","start":"2026-06-02T23:50:31.58249Z","end":"2026-06-02T23:50:32.15647Z","steps":["trace[381772847] 'agreement among raft nodes before linearized reading' (duration: 573.842798ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:50:32.156523Z","caller":"traceutil/trace.go:171","msg":"trace[1870233530] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"629.346937ms","start":"2026-06-02T23:50:31.527163Z","end":"2026-06-02T23:50:32.15651Z","steps":["trace[1870233530] 'agreement among raft nodes before linearized reading' (duration: 629.279456ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:50:32.156545Z","caller":"traceutil/trace.go:171","msg":"trace[1828581175] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:0; response_revision:10; }","duration":"564.823104ms","start":"2026-06-02T23:50:31.59171Z","end":"2026-06-02T23:50:32.156533Z","steps":["trace[1828581175] 'agreement among raft nodes before linearized reading' (duration: 564.749902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:32.156593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.582477Z","time spent":"574.069724ms","remote":"127.0.0.1:35004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-02T23:50:32.156539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.634825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-02T23:50:32.156618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.591692Z","time spent":"564.914837ms","remote":"127.0.0.1:34932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-06-02T23:50:32.156614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.527153Z","time spent":"629.438439ms","remote":"127.0.0.1:35292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-06-02T23:50:32.156654Z","caller":"traceutil/trace.go:171","msg":"trace[999730784] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"511.787248ms","start":"2026-06-02T23:50:31.644856Z","end":"2026-06-02T23:50:32.156643Z","steps":["trace[999730784] 'agreement among raft nodes before linearized reading' (duration: 511.657385ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:50:32.156684Z","caller":"traceutil/trace.go:171","msg":"trace[958608562] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"553.062398ms","start":"2026-06-02T23:50:31.603611Z","end":"2026-06-02T23:50:32.156674Z","steps":["trace[958608562] 'process raft request' (duration: 551.486557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:32.156703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.64484Z","time spent":"511.853652ms","remote":"127.0.0.1:35004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-02T23:50:32.15674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.603585Z","time spent":"553.124302ms","remote":"127.0.0.1:35392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:50:32.156416Z","caller":"traceutil/trace.go:171","msg":"trace[865461964] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"561.386945ms","start":"2026-06-02T23:50:31.595009Z","end":"2026-06-02T23:50:32.156395Z","steps":["trace[865461964] 'agreement among raft nodes before linearized reading' (duration: 561.296993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:32.156812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.594994Z","time spent":"561.804176ms","remote":"127.0.0.1:34976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-06-02T23:50:32.15683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.84758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-02T23:50:32.156894Z","caller":"traceutil/trace.go:171","msg":"trace[935857120] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"558.922862ms","start":"2026-06-02T23:50:31.597948Z","end":"2026-06-02T23:50:32.156871Z","steps":["trace[935857120] 'agreement among raft nodes before linearized reading' (duration: 558.84406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:32.156946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:31.59794Z","time spent":"558.993634ms","remote":"127.0.0.1:35004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-02T23:51:03.120667Z","caller":"traceutil/trace.go:171","msg":"trace[2111009775] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"189.387961ms","start":"2026-06-02T23:51:02.931259Z","end":"2026-06-02T23:51:03.120647Z","steps":["trace[2111009775] 'process raft request' (duration: 189.278768ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:05.983989Z","caller":"traceutil/trace.go:171","msg":"trace[534202009] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"276.850988ms","start":"2026-06-02T23:51:05.707091Z","end":"2026-06-02T23:51:05.983942Z","steps":["trace[534202009] 'process raft request' (duration: 276.644422ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.039633Z","caller":"traceutil/trace.go:171","msg":"trace[2079293134] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"316.221579ms","start":"2026-06-02T23:51:05.723376Z","end":"2026-06-02T23:51:06.039597Z","steps":["trace[2079293134] 'process raft request' (duration: 315.994673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:06.039817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:05.723359Z","time spent":"316.392573ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:51:06.039946Z","caller":"traceutil/trace.go:171","msg":"trace[19702378] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"305.228653ms","start":"2026-06-02T23:51:05.734706Z","end":"2026-06-02T23:51:06.039935Z","steps":["trace[19702378] 'process raft request' (duration: 304.915595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:06.039995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:05.7347Z","time spent":"305.274625ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:51:06.040089Z","caller":"traceutil/trace.go:171","msg":"trace[1225121137] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"296.98017ms","start":"2026-06-02T23:51:05.743096Z","end":"2026-06-02T23:51:06.040076Z","steps":["trace[1225121137] 'process raft request' (duration: 296.595009ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.040181Z","caller":"traceutil/trace.go:171","msg":"trace[800446357] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"297.143024ms","start":"2026-06-02T23:51:05.743022Z","end":"2026-06-02T23:51:06.040165Z","steps":["trace[800446357] 'process raft request' (duration: 296.6382ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.040315Z","caller":"traceutil/trace.go:171","msg":"trace[570124708] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"309.045252ms","start":"2026-06-02T23:51:05.731214Z","end":"2026-06-02T23:51:06.040259Z","steps":["trace[570124708] 'process raft request' (duration: 308.364464ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.040489Z","caller":"traceutil/trace.go:171","msg":"trace[947486379] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"248.196545ms","start":"2026-06-02T23:51:05.792283Z","end":"2026-06-02T23:51:06.04048Z","steps":["trace[947486379] 'process raft request' (duration: 247.99688ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.040255Z","caller":"traceutil/trace.go:171","msg":"trace[1473684300] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"294.565547ms","start":"2026-06-02T23:51:05.745662Z","end":"2026-06-02T23:51:06.040227Z","steps":["trace[1473684300] 'process raft request' (duration: 294.068704ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.040433Z","caller":"traceutil/trace.go:171","msg":"trace[327138776] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"270.538954ms","start":"2026-06-02T23:51:05.769843Z","end":"2026-06-02T23:51:06.040382Z","steps":["trace[327138776] 'process raft request' (duration: 270.240727ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.040361Z","caller":"traceutil/trace.go:171","msg":"trace[602268166] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"294.585418ms","start":"2026-06-02T23:51:05.745765Z","end":"2026-06-02T23:51:06.040351Z","steps":["trace[602268166] 'process raft request' (duration: 294.145338ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.040721Z","caller":"traceutil/trace.go:171","msg":"trace[490075458] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"144.701491ms","start":"2026-06-02T23:51:05.895981Z","end":"2026-06-02T23:51:06.040682Z","steps":["trace[490075458] 'process raft request' (duration: 144.378853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:06.040922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:05.731208Z","time spent":"309.380951ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:51:06.041243Z","caller":"traceutil/trace.go:171","msg":"trace[408888231] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"309.014222ms","start":"2026-06-02T23:51:05.731044Z","end":"2026-06-02T23:51:06.040058Z","steps":["trace[408888231] 'process raft request' (duration: 308.428577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:06.042504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:05.73103Z","time spent":"310.262714ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:51:06.104165Z","caller":"traceutil/trace.go:171","msg":"trace[1123944812] transaction","detail":"{read_only:false; number_of_response:1; response_revision:498; }","duration":"111.683965ms","start":"2026-06-02T23:51:05.992469Z","end":"2026-06-02T23:51:06.104153Z","steps":["trace[1123944812] 'process raft request' (duration: 111.661145ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.10436Z","caller":"traceutil/trace.go:171","msg":"trace[658729189] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"114.227203ms","start":"2026-06-02T23:51:05.990106Z","end":"2026-06-02T23:51:06.104333Z","steps":["trace[658729189] 'process raft request' (duration: 113.877253ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.104583Z","caller":"traceutil/trace.go:171","msg":"trace[1684898676] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"112.194869ms","start":"2026-06-02T23:51:05.992382Z","end":"2026-06-02T23:51:06.104577Z","steps":["trace[1684898676] 'process raft request' (duration: 111.696886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:06.572833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.541912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:51:06.573155Z","caller":"traceutil/trace.go:171","msg":"trace[146422393] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"518.971134ms","start":"2026-06-02T23:51:06.054154Z","end":"2026-06-02T23:51:06.573125Z","steps":["trace[146422393] 'process raft request' (duration: 518.81971ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.573214Z","caller":"traceutil/trace.go:171","msg":"trace[755130] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"515.71202ms","start":"2026-06-02T23:51:06.057487Z","end":"2026-06-02T23:51:06.573199Z","steps":["trace[755130] 'process raft request' (duration: 515.608197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:06.573299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.057481Z","time spent":"515.781512ms","remote":"127.0.0.1:35392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:51:06.573488Z","caller":"traceutil/trace.go:171","msg":"trace[2080585146] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"516.726507ms","start":"2026-06-02T23:51:06.056719Z","end":"2026-06-02T23:51:06.573446Z","steps":["trace[2080585146] 'process raft request' (duration: 516.319046ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.573723Z","caller":"traceutil/trace.go:171","msg":"trace[1892141909] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"522.984749ms","start":"2026-06-02T23:51:06.050695Z","end":"2026-06-02T23:51:06.57368Z","steps":["trace[1892141909] 'process raft request' (duration: 91.40134ms)","trace[1892141909] 'compare' (duration: 430.289585ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:51:06.576485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.056713Z","time spent":"519.660853ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:51:06.57655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.054143Z","time spent":"519.075197ms","remote":"127.0.0.1:35392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:51:06.576546Z","caller":"traceutil/trace.go:171","msg":"trace[470012613] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"464.513982ms","start":"2026-06-02T23:51:06.11201Z","end":"2026-06-02T23:51:06.576524Z","steps":["trace[470012613] 'process raft request' (duration: 464.395599ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.576625Z","caller":"traceutil/trace.go:171","msg":"trace[1333082750] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"515.184156ms","start":"2026-06-02T23:51:06.061418Z","end":"2026-06-02T23:51:06.576602Z","steps":["trace[1333082750] 'process raft request' (duration: 511.723007ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.576631Z","caller":"traceutil/trace.go:171","msg":"trace[1809107434] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"513.685948ms","start":"2026-06-02T23:51:06.062914Z","end":"2026-06-02T23:51:06.5766Z","steps":["trace[1809107434] 'process raft request' (duration: 510.454925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:06.580051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.062898Z","time spent":"517.138877ms","remote":"127.0.0.1:35392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:51:06.576795Z","caller":"traceutil/trace.go:171","msg":"trace[2017285245] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"506.427999ms","start":"2026-06-02T23:51:06.070348Z","end":"2026-06-02T23:51:06.576776Z","steps":["trace[2017285245] 'process raft request' (duration: 503.235397ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.576872Z","caller":"traceutil/trace.go:171","msg":"trace[563041365] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"497.663442ms","start":"2026-06-02T23:51:06.079194Z","end":"2026-06-02T23:51:06.576858Z","steps":["trace[563041365] 'process raft request' (duration: 494.446649ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.57714Z","caller":"traceutil/trace.go:171","msg":"trace[97695129] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"472.914921ms","start":"2026-06-02T23:51:06.10393Z","end":"2026-06-02T23:51:06.576845Z","steps":["trace[97695129] 'process raft request' (duration: 469.80232ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:06.580182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.103913Z","time spent":"476.255388ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:51:06.579204Z","caller":"traceutil/trace.go:171","msg":"trace[991002843] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"465.51772ms","start":"2026-06-02T23:51:06.11367Z","end":"2026-06-02T23:51:06.579187Z","steps":["trace[991002843] 'process raft request' (duration: 463.821885ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.579274Z","caller":"traceutil/trace.go:171","msg":"trace[799992143] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"462.936652ms","start":"2026-06-02T23:51:06.116321Z","end":"2026-06-02T23:51:06.579258Z","steps":["trace[799992143] 'process raft request' (duration: 462.451139ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:06.579396Z","caller":"traceutil/trace.go:171","msg":"trace[1618760878] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:517; }","duration":"373.566655ms","start":"2026-06-02T23:51:06.205792Z","end":"2026-06-02T23:51:06.579359Z","steps":["trace[1618760878] 'read index received' (duration: 313.840557ms)","trace[1618760878] 'applied index is now lower than readState.Index' (duration: 59.487802ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:51:06.581595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.050679Z","time spent":"530.874834ms","remote":"127.0.0.1:35392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T23:51:06.582627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.828869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-02T23:51:06.582765Z","caller":"traceutil/trace.go:171","msg":"trace[270620414] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:510; }","duration":"376.935493ms","start":"2026-06-02T23:51:06.205741Z","end":"2026-06-02T23:51:06.582677Z","steps":["trace[270620414] 'agreement among raft nodes before linearized reading' (duration: 373.681428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:06.582911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.205721Z","time spent":"377.109287ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T23:51:06.583303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.111991Z","time spent":"467.944392ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:51:06.583662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.070332Z","time spent":"509.771016ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:51:06.580152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.079172Z","time spent":"500.962108ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:51:06.583874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.113653Z","time spent":"466.563606ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:51:06.580309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.11631Z","time spent":"463.974638ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:51:06.584122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.741352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:51:06.584153Z","caller":"traceutil/trace.go:171","msg":"trace[1623183810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"370.806244ms","start":"2026-06-02T23:51:06.213336Z","end":"2026-06-02T23:51:06.584142Z","steps":["trace[1623183810] 'agreement among raft nodes before linearized reading' (duration: 370.762013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:06.584173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.213315Z","time spent":"370.854165ms","remote":"127.0.0.1:34912","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-02T23:51:06.58425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.460633ms","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-02T23:51:06.584284Z","caller":"traceutil/trace.go:171","msg":"trace[345662183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"142.518324ms","start":"2026-06-02T23:51:06.441755Z","end":"2026-06-02T23:51:06.584273Z","steps":["trace[345662183] 'agreement among raft nodes before linearized reading' (duration: 142.412352ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:06.584333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.134512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T23:51:06.584357Z","caller":"traceutil/trace.go:171","msg":"trace[1110103215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"145.158183ms","start":"2026-06-02T23:51:06.439192Z","end":"2026-06-02T23:51:06.58435Z","steps":["trace[1110103215] 'agreement among raft nodes before linearized reading' (duration: 145.075961ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:06.583372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:06.061411Z","time spent":"518.590106ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:51:12.692895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.496713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:51:12.693002Z","caller":"traceutil/trace.go:171","msg":"trace[754352326] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:590; }","duration":"290.647866ms","start":"2026-06-02T23:51:12.402332Z","end":"2026-06-02T23:51:12.69298Z","steps":["trace[754352326] 'range keys from in-memory index tree' (duration: 290.41561ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:13.056043Z","caller":"traceutil/trace.go:171","msg":"trace[1998929605] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"263.863881ms","start":"2026-06-02T23:51:12.792156Z","end":"2026-06-02T23:51:13.05602Z","steps":["trace[1998929605] 'process raft request' (duration: 263.710557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.21576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997854332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T23:51:15.215894Z","caller":"traceutil/trace.go:171","msg":"trace[1476897754] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.998138939s","start":"2026-06-02T23:51:13.217727Z","end":"2026-06-02T23:51:15.215866Z","steps":["trace[1476897754] 'agreement among raft nodes before linearized reading' (duration: 1.997875103s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.215955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:13.217711Z","time spent":"1.998232031s","remote":"127.0.0.1:34912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/02 23:51:15 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T23:51:15.354639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.077256683s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:51:15.354951Z","caller":"traceutil/trace.go:171","msg":"trace[891904334] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"398.295181ms","start":"2026-06-02T23:51:14.956638Z","end":"2026-06-02T23:51:15.354934Z","steps":["trace[891904334] 'process raft request' (duration: 398.25319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.355017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:14.956616Z","time spent":"398.369814ms","remote":"127.0.0.1:35158","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-02T23:51:15.355196Z","caller":"traceutil/trace.go:171","msg":"trace[697642753] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"2.1576525s","start":"2026-06-02T23:51:13.197532Z","end":"2026-06-02T23:51:15.355184Z","steps":["trace[697642753] 'process raft request' (duration: 79.759641ms)","trace[697642753] 'compare' (duration: 2.077042438s)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:51:15.355246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:13.197431Z","time spent":"2.157787884s","remote":"127.0.0.1:34932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:51:15.35536Z","caller":"traceutil/trace.go:171","msg":"trace[1377179269] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"2.137591471s","start":"2026-06-02T23:51:13.217759Z","end":"2026-06-02T23:51:15.35535Z","steps":["trace[1377179269] 'read index received' (duration: 59.536306ms)","trace[1377179269] 'applied index is now lower than readState.Index' (duration: 2.078054045s)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:51:15.355493Z","caller":"traceutil/trace.go:171","msg":"trace[1007724359] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.123964086s","start":"2026-06-02T23:51:13.23152Z","end":"2026-06-02T23:51:15.355484Z","steps":["trace[1007724359] 'process raft request' (duration: 2.123253827s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.355534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:13.231504Z","time spent":"2.124009608s","remote":"127.0.0.1:35140","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-02T23:51:15.355806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.54718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T23:51:15.355843Z","caller":"traceutil/trace.go:171","msg":"trace[562084774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"623.629832ms","start":"2026-06-02T23:51:14.732203Z","end":"2026-06-02T23:51:15.355833Z","steps":["trace[562084774] 'agreement among raft nodes before linearized reading' (duration: 623.442038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.355873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:14.732185Z","time spent":"623.681043ms","remote":"127.0.0.1:35158","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-02T23:51:15.355929Z","caller":"traceutil/trace.go:171","msg":"trace[546132674] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"2.032982534s","start":"2026-06-02T23:51:13.322911Z","end":"2026-06-02T23:51:15.355894Z","steps":["trace[546132674] 'process raft request' (duration: 2.031933907s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.356069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.43323ms","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-02T23:51:15.356101Z","caller":"traceutil/trace.go:171","msg":"trace[601512018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"580.491583ms","start":"2026-06-02T23:51:14.7756Z","end":"2026-06-02T23:51:15.356092Z","steps":["trace[601512018] 'agreement among raft nodes before linearized reading' (duration: 580.403961ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.356095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:13.322885Z","time spent":"2.033118878s","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:51:15.356008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.901281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:51:15.356104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.953692ms","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-02T23:51:15.356127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:14.775581Z","time spent":"580.539454ms","remote":"127.0.0.1:35158","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-02T23:51:15.356159Z","caller":"traceutil/trace.go:171","msg":"trace[914503412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"579.028794ms","start":"2026-06-02T23:51:14.777116Z","end":"2026-06-02T23:51:15.356144Z","steps":["trace[914503412] 'agreement among raft nodes before linearized reading' (duration: 578.878709ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:15.356158Z","caller":"traceutil/trace.go:171","msg":"trace[470677908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"140.084077ms","start":"2026-06-02T23:51:15.216052Z","end":"2026-06-02T23:51:15.356136Z","steps":["trace[470677908] 'agreement among raft nodes before linearized reading' (duration: 139.905822ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.356188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:14.777104Z","time spent":"579.079655ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T23:51:15.356356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.221324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-02T23:51:15.356391Z","caller":"traceutil/trace.go:171","msg":"trace[1352963157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"174.295586ms","start":"2026-06-02T23:51:15.182084Z","end":"2026-06-02T23:51:15.35638Z","steps":["trace[1352963157] 'agreement among raft nodes before linearized reading' (duration: 174.160163ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:17.079996Z","caller":"traceutil/trace.go:171","msg":"trace[697154679] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"141.792472ms","start":"2026-06-02T23:51:16.938151Z","end":"2026-06-02T23:51:17.079943Z","steps":["trace[697154679] 'process raft request' (duration: 140.879549ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:21.511381Z","caller":"traceutil/trace.go:171","msg":"trace[144799609] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"109.806311ms","start":"2026-06-02T23:51:21.401539Z","end":"2026-06-02T23:51:21.511345Z","steps":["trace[144799609] 'process raft request' (duration: 108.857276ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:21.5122Z","caller":"traceutil/trace.go:171","msg":"trace[525713807] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"110.15145ms","start":"2026-06-02T23:51:21.402038Z","end":"2026-06-02T23:51:21.512189Z","steps":["trace[525713807] 'process raft request' (duration: 109.077102ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:21.512274Z","caller":"traceutil/trace.go:171","msg":"trace[1588507732] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"108.108987ms","start":"2026-06-02T23:51:21.404134Z","end":"2026-06-02T23:51:21.512243Z","steps":["trace[1588507732] 'process raft request' (duration: 107.988524ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:36.055083Z","caller":"traceutil/trace.go:171","msg":"trace[1014034433] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"118.603601ms","start":"2026-06-02T23:51:35.936457Z","end":"2026-06-02T23:51:36.055061Z","steps":["trace[1014034433] 'process raft request' (duration: 117.286346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:38.879008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.19488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T23:51:38.879114Z","caller":"traceutil/trace.go:171","msg":"trace[1890960065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:786; }","duration":"110.346444ms","start":"2026-06-02T23:51:38.76875Z","end":"2026-06-02T23:51:38.879096Z","steps":["trace[1890960065] 'range keys from in-memory index tree' (duration: 110.036877ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:39.051972Z","caller":"traceutil/trace.go:171","msg":"trace[1911339257] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"167.976471ms","start":"2026-06-02T23:51:38.88379Z","end":"2026-06-02T23:51:39.051766Z","steps":["trace[1911339257] 'process raft request' (duration: 167.852708ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:39.213629Z","caller":"traceutil/trace.go:171","msg":"trace[683426489] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"286.756155ms","start":"2026-06-02T23:51:38.926847Z","end":"2026-06-02T23:51:39.213603Z","steps":["trace[683426489] 'process raft request' (duration: 286.5718ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:53.3079Z","caller":"traceutil/trace.go:171","msg":"trace[1068902443] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"132.834339ms","start":"2026-06-02T23:51:53.175034Z","end":"2026-06-02T23:51:53.307868Z","steps":["trace[1068902443] 'read index received' (duration: 132.610873ms)","trace[1068902443] 'applied index is now lower than readState.Index' (duration: 222.036µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:51:53.308143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.082515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-02T23:51:53.308219Z","caller":"traceutil/trace.go:171","msg":"trace[1577182111] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:916; }","duration":"133.190139ms","start":"2026-06-02T23:51:53.175013Z","end":"2026-06-02T23:51:53.308203Z","steps":["trace[1577182111] 'agreement among raft nodes before linearized reading' (duration: 132.974553ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:53.308005Z","caller":"traceutil/trace.go:171","msg":"trace[1781156100] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"142.53515ms","start":"2026-06-02T23:51:53.165422Z","end":"2026-06-02T23:51:53.307958Z","steps":["trace[1781156100] 'process raft request' (duration: 142.251033ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:53.898744Z","caller":"traceutil/trace.go:171","msg":"trace[1919900326] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"133.472505ms","start":"2026-06-02T23:51:53.765243Z","end":"2026-06-02T23:51:53.898716Z","steps":["trace[1919900326] 'process raft request' (duration: 132.366467ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:53.898767Z","caller":"traceutil/trace.go:171","msg":"trace[273794048] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"133.499636ms","start":"2026-06-02T23:51:53.765244Z","end":"2026-06-02T23:51:53.898743Z","steps":["trace[273794048] 'process raft request' (duration: 133.376303ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:53.898891Z","caller":"traceutil/trace.go:171","msg":"trace[1119915739] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"128.043734ms","start":"2026-06-02T23:51:53.770817Z","end":"2026-06-02T23:51:53.898861Z","steps":["trace[1119915739] 'process raft request' (duration: 127.843829ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:52:07.016596Z","caller":"traceutil/trace.go:171","msg":"trace[87676108] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"123.029044ms","start":"2026-06-02T23:52:06.89355Z","end":"2026-06-02T23:52:07.016579Z","steps":["trace[87676108] 'process raft request' (duration: 122.904161ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:52:14.118397Z","caller":"traceutil/trace.go:171","msg":"trace[971700343] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"185.908858ms","start":"2026-06-02T23:52:13.932461Z","end":"2026-06-02T23:52:14.11837Z","steps":["trace[971700343] 'read index received' (duration: 185.669993ms)","trace[971700343] 'applied index is now lower than readState.Index' (duration: 237.725µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:52:14.118505Z","caller":"traceutil/trace.go:171","msg":"trace[153553354] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"262.619301ms","start":"2026-06-02T23:52:13.85586Z","end":"2026-06-02T23:52:14.118479Z","steps":["trace[153553354] 'process raft request' (duration: 262.363334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:14.118633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.162756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T23:52:14.118724Z","caller":"traceutil/trace.go:171","msg":"trace[1208406145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"186.281539ms","start":"2026-06-02T23:52:13.932404Z","end":"2026-06-02T23:52:14.118686Z","steps":["trace[1208406145] 'agreement among raft nodes before linearized reading' (duration: 186.068894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:14.713766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368099989252471,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T23:52:15.214542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368099989252471,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T23:52:15.288234Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.166612708s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T23:52:15.288624Z","caller":"traceutil/trace.go:171","msg":"trace[1573834035] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"1.16706125s","start":"2026-06-02T23:52:14.121537Z","end":"2026-06-02T23:52:15.288598Z","steps":["trace[1573834035] 'process raft request' (duration: 1.166909686s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:15.288782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:14.121512Z","time spent":"1.167197333s","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:52:15.390982Z","caller":"traceutil/trace.go:171","msg":"trace[836753323] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.268480995s","start":"2026-06-02T23:52:14.122476Z","end":"2026-06-02T23:52:15.390957Z","steps":["trace[836753323] 'process raft request' (duration: 1.268399383s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:15.391129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:14.122469Z","time spent":"1.268586387s","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:52:15.39114Z","caller":"traceutil/trace.go:171","msg":"trace[1932410557] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"1.255970589s","start":"2026-06-02T23:52:14.135153Z","end":"2026-06-02T23:52:15.391124Z","steps":["trace[1932410557] 'process raft request' (duration: 1.255765854s)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:52:15.391138Z","caller":"traceutil/trace.go:171","msg":"trace[1353587435] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1036; }","duration":"1.177771799s","start":"2026-06-02T23:52:14.213338Z","end":"2026-06-02T23:52:15.39111Z","steps":["trace[1353587435] 'read index received' (duration: 1.075119902s)","trace[1353587435] 'applied index is now lower than readState.Index' (duration: 102.650147ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:52:15.391265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:14.135134Z","time spent":"1.256048041s","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:52:15.391317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177962124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:52:15.391355Z","caller":"traceutil/trace.go:171","msg":"trace[1380687649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"1.178046807s","start":"2026-06-02T23:52:14.213297Z","end":"2026-06-02T23:52:15.391344Z","steps":["trace[1380687649] 'agreement among raft nodes before linearized reading' (duration: 1.177948484s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:15.391354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.938078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:52:15.391388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:14.213273Z","time spent":"1.178107088s","remote":"127.0.0.1:34912","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-02T23:52:15.391402Z","caller":"traceutil/trace.go:171","msg":"trace[1551783229] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1003; }","duration":"503.022821ms","start":"2026-06-02T23:52:14.888366Z","end":"2026-06-02T23:52:15.391389Z","steps":["trace[1551783229] 'agreement among raft nodes before linearized reading' (duration: 502.919958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:15.391441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:14.888349Z","time spent":"503.082832ms","remote":"127.0.0.1:56340","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/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-02T23:52:15.391473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.283467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T23:52:15.391511Z","caller":"traceutil/trace.go:171","msg":"trace[1735852055] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1003; }","duration":"296.344248ms","start":"2026-06-02T23:52:15.095155Z","end":"2026-06-02T23:52:15.391499Z","steps":["trace[1735852055] 'agreement among raft nodes before linearized reading' (duration: 296.271637ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:52:15.391021Z","caller":"traceutil/trace.go:171","msg":"trace[1613776380] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.269009708s","start":"2026-06-02T23:52:14.121979Z","end":"2026-06-02T23:52:15.390988Z","steps":["trace[1613776380] 'process raft request' (duration: 1.238636756s)","trace[1613776380] 'compare' (duration: 30.139656ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:52:15.391907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:14.121962Z","time spent":"1.26983926s","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:52:16.180072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.618729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:52:16.180596Z","caller":"traceutil/trace.go:171","msg":"trace[365082210] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"713.439467ms","start":"2026-06-02T23:52:15.46713Z","end":"2026-06-02T23:52:16.18057Z","steps":["trace[365082210] 'process raft request' (duration: 348.237173ms)","trace[365082210] 'compare' (duration: 364.524507ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:52:16.180672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:15.467116Z","time spent":"713.517729ms","remote":"127.0.0.1:35158","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-02T23:52:33.309261Z","caller":"traceutil/trace.go:171","msg":"trace[484481139] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"101.256031ms","start":"2026-06-02T23:52:33.207988Z","end":"2026-06-02T23:52:33.309244Z","steps":["trace[484481139] 'process raft request' (duration: 95.632034ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:52:37.579255Z","caller":"traceutil/trace.go:171","msg":"trace[348304203] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"202.184153ms","start":"2026-06-02T23:52:37.377035Z","end":"2026-06-02T23:52:37.579219Z","steps":["trace[348304203] 'read index received' (duration: 202.020929ms)","trace[348304203] 'applied index is now lower than readState.Index' (duration: 161.494µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:52:37.579345Z","caller":"traceutil/trace.go:171","msg":"trace[1301481461] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"316.25883ms","start":"2026-06-02T23:52:37.263055Z","end":"2026-06-02T23:52:37.579313Z","steps":["trace[1301481461] 'process raft request' (duration: 315.960411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:37.579497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:37.263031Z","time spent":"316.383162ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:52:37.579504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.456651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-02T23:52:37.579588Z","caller":"traceutil/trace.go:171","msg":"trace[1004350289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1073; }","duration":"202.575914ms","start":"2026-06-02T23:52:37.376985Z","end":"2026-06-02T23:52:37.579561Z","steps":["trace[1004350289] 'agreement among raft nodes before linearized reading' (duration: 202.363108ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:52:38.063039Z","caller":"traceutil/trace.go:171","msg":"trace[179761086] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"599.654541ms","start":"2026-06-02T23:52:37.463353Z","end":"2026-06-02T23:52:38.063008Z","steps":["trace[179761086] 'process raft request' (duration: 599.489967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:38.063266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:37.463325Z","time spent":"599.782525ms","remote":"127.0.0.1:35158","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-02T23:52:38.063678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.282541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T23:52:38.064184Z","caller":"traceutil/trace.go:171","msg":"trace[1994913260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"543.858186ms","start":"2026-06-02T23:52:37.520302Z","end":"2026-06-02T23:52:38.06416Z","steps":["trace[1994913260] 'agreement among raft nodes before linearized reading' (duration: 543.127037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:38.063945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.324076ms","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-02T23:52:38.064392Z","caller":"traceutil/trace.go:171","msg":"trace[528519721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1074; }","duration":"514.815808ms","start":"2026-06-02T23:52:37.549543Z","end":"2026-06-02T23:52:38.064359Z","steps":["trace[528519721] 'agreement among raft nodes before linearized reading' (duration: 513.475303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:38.064482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:37.549522Z","time spent":"514.931282ms","remote":"127.0.0.1:35158","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-02T23:52:38.062989Z","caller":"traceutil/trace.go:171","msg":"trace[701973055] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"483.565022ms","start":"2026-06-02T23:52:37.579402Z","end":"2026-06-02T23:52:38.062967Z","steps":["trace[701973055] 'read index received' (duration: 483.436639ms)","trace[701973055] 'applied index is now lower than readState.Index' (duration: 127.053µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:52:38.064995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:37.52027Z","time spent":"544.042861ms","remote":"127.0.0.1:35158","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-02T23:52:38.079513Z","caller":"traceutil/trace.go:171","msg":"trace[1054660305] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"495.372092ms","start":"2026-06-02T23:52:37.584122Z","end":"2026-06-02T23:52:38.079494Z","steps":["trace[1054660305] 'process raft request' (duration: 495.231648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:38.079782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.65083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T23:52:38.079853Z","caller":"traceutil/trace.go:171","msg":"trace[2008372913] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1075; }","duration":"372.761692ms","start":"2026-06-02T23:52:37.70708Z","end":"2026-06-02T23:52:38.079842Z","steps":["trace[2008372913] 'agreement among raft nodes before linearized reading' (duration: 372.66682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:38.079898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:37.707059Z","time spent":"372.828986ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-02T23:52:38.080595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:37.584098Z","time spent":"495.483804ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:52:39.913244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05204982s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:52:39.913369Z","caller":"traceutil/trace.go:171","msg":"trace[1334799160] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1079; }","duration":"1.052186983s","start":"2026-06-02T23:52:38.861164Z","end":"2026-06-02T23:52:39.913351Z","steps":["trace[1334799160] 'range keys from in-memory index tree' (duration: 1.05203285s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:40.256942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244366027s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T23:52:40.257039Z","caller":"traceutil/trace.go:171","msg":"trace[2076040060] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"1.509210825s","start":"2026-06-02T23:52:38.74781Z","end":"2026-06-02T23:52:40.257021Z","steps":["trace[2076040060] 'read index received' (duration: 264.689094ms)","trace[2076040060] 'applied index is now lower than readState.Index' (duration: 1.244520401s)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:52:40.257311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509488033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T23:52:40.25735Z","caller":"traceutil/trace.go:171","msg":"trace[1962275016] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1079; }","duration":"1.509573025s","start":"2026-06-02T23:52:38.747767Z","end":"2026-06-02T23:52:40.257341Z","steps":["trace[1962275016] 'agreement among raft nodes before linearized reading' (duration: 1.509486463s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:40.257385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:38.747748Z","time spent":"1.509626687s","remote":"127.0.0.1:35324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-06-02T23:52:40.282527Z","caller":"traceutil/trace.go:171","msg":"trace[453203704] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"214.728931ms","start":"2026-06-02T23:52:40.067771Z","end":"2026-06-02T23:52:40.2825Z","steps":["trace[453203704] 'process raft request' (duration: 214.565086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:40.282881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.140971ms","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-02T23:52:40.282931Z","caller":"traceutil/trace.go:171","msg":"trace[1103307763] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1080; }","duration":"639.232243ms","start":"2026-06-02T23:52:39.643686Z","end":"2026-06-02T23:52:40.282918Z","steps":["trace[1103307763] 'agreement among raft nodes before linearized reading' (duration: 639.156722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:40.28297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:39.643666Z","time spent":"639.291065ms","remote":"127.0.0.1:56370","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-02T23:52:40.283152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.307189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T23:52:40.28327Z","caller":"traceutil/trace.go:171","msg":"trace[601654711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1080; }","duration":"194.460662ms","start":"2026-06-02T23:52:40.088769Z","end":"2026-06-02T23:52:40.28323Z","steps":["trace[601654711] 'agreement among raft nodes before linearized reading' (duration: 194.035961ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:40.28336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.939416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-02T23:52:40.283403Z","caller":"traceutil/trace.go:171","msg":"trace[1062449358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1080; }","duration":"199.999987ms","start":"2026-06-02T23:52:40.083393Z","end":"2026-06-02T23:52:40.283393Z","steps":["trace[1062449358] 'agreement among raft nodes before linearized reading' (duration: 199.865304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:40.283393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067930117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:52:40.283466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.795235ms","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-02T23:52:40.283501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161719101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T23:52:40.283534Z","caller":"traceutil/trace.go:171","msg":"trace[930820542] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1080; }","duration":"1.162486041s","start":"2026-06-02T23:52:39.121039Z","end":"2026-06-02T23:52:40.283525Z","steps":["trace[930820542] 'agreement among raft nodes before linearized reading' (duration: 1.161719551s)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:52:40.283535Z","caller":"traceutil/trace.go:171","msg":"trace[131593534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1080; }","duration":"193.898437ms","start":"2026-06-02T23:52:40.08962Z","end":"2026-06-02T23:52:40.283518Z","steps":["trace[131593534] 'agreement among raft nodes before linearized reading' (duration: 193.61795ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:52:40.283575Z","caller":"traceutil/trace.go:171","msg":"trace[745917079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"1.068185103s","start":"2026-06-02T23:52:39.215313Z","end":"2026-06-02T23:52:40.283499Z","steps":["trace[745917079] 'agreement among raft nodes before linearized reading' (duration: 1.067867555s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:40.283802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:39.121019Z","time spent":"1.162547823s","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-06-02T23:52:40.283844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:39.215298Z","time spent":"1.068363168s","remote":"127.0.0.1:34912","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-02T23:52:42.608613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.248302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:52:42.608725Z","caller":"traceutil/trace.go:171","msg":"trace[133217503] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"185.012465ms","start":"2026-06-02T23:52:42.423695Z","end":"2026-06-02T23:52:42.608708Z","steps":["trace[133217503] 'read index received' (duration: 22.514287ms)","trace[133217503] 'applied index is now lower than readState.Index' (duration: 162.496818ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:52:42.60878Z","caller":"traceutil/trace.go:171","msg":"trace[274500213] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"210.609093ms","start":"2026-06-02T23:52:42.398137Z","end":"2026-06-02T23:52:42.608746Z","steps":["trace[274500213] 'process raft request' (duration: 48.142595ms)","trace[274500213] 'compare' (duration: 162.15391ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:52:42.608957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.263193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T23:52:42.609001Z","caller":"traceutil/trace.go:171","msg":"trace[386376927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"185.338915ms","start":"2026-06-02T23:52:42.423647Z","end":"2026-06-02T23:52:42.608986Z","steps":["trace[386376927] 'agreement among raft nodes before linearized reading' (duration: 185.119529ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:52:43.300292Z","caller":"traceutil/trace.go:171","msg":"trace[2125487630] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"114.900047ms","start":"2026-06-02T23:52:43.185371Z","end":"2026-06-02T23:52:43.300271Z","steps":["trace[2125487630] 'process raft request' (duration: 107.328819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:58.962267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368099989252931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T23:52:59.241988Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017688876s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T23:52:59.242462Z","caller":"traceutil/trace.go:171","msg":"trace[2015055491] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"1.01821564s","start":"2026-06-02T23:52:58.224219Z","end":"2026-06-02T23:52:59.242435Z","steps":["trace[2015055491] 'process raft request' (duration: 1.018028374s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:59.242637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:58.224205Z","time spent":"1.018335522s","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:52:59.363325Z","caller":"traceutil/trace.go:171","msg":"trace[206826000] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1237; }","duration":"901.69499ms","start":"2026-06-02T23:52:58.461601Z","end":"2026-06-02T23:52:59.363296Z","steps":["trace[206826000] 'read index received' (duration: 780.698863ms)","trace[206826000] 'applied index is now lower than readState.Index' (duration: 120.994897ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:52:59.363408Z","caller":"traceutil/trace.go:171","msg":"trace[718544857] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"1.000072505s","start":"2026-06-02T23:52:58.363312Z","end":"2026-06-02T23:52:59.363385Z","steps":["trace[718544857] 'process raft request' (duration: 999.839189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:59.363543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:58.363296Z","time spent":"1.000168779s","remote":"127.0.0.1:35158","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-02T23:52:59.363576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.965127ms","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-02T23:52:59.363612Z","caller":"traceutil/trace.go:171","msg":"trace[222704512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1192; }","duration":"902.031539ms","start":"2026-06-02T23:52:58.461571Z","end":"2026-06-02T23:52:59.363602Z","steps":["trace[222704512] 'agreement among raft nodes before linearized reading' (duration: 901.891736ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:59.363633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:58.461558Z","time spent":"902.06909ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T23:52:59.363729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.271367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T23:52:59.363749Z","caller":"traceutil/trace.go:171","msg":"trace[1524085130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1192; }","duration":"641.336579ms","start":"2026-06-02T23:52:58.722406Z","end":"2026-06-02T23:52:59.363743Z","steps":["trace[1524085130] 'agreement among raft nodes before linearized reading' (duration: 641.282257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:59.363771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:58.72238Z","time spent":"641.38501ms","remote":"127.0.0.1:35158","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-02T23:52:59.363754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.434147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-02T23:52:59.363826Z","caller":"traceutil/trace.go:171","msg":"trace[1157841313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1192; }","duration":"813.54057ms","start":"2026-06-02T23:52:58.550267Z","end":"2026-06-02T23:52:59.363807Z","steps":["trace[1157841313] 'agreement among raft nodes before linearized reading' (duration: 813.356806ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:59.363848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.194146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:52:59.363863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:58.550243Z","time spent":"813.611982ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T23:52:59.363873Z","caller":"traceutil/trace.go:171","msg":"trace[155436192] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1192; }","duration":"291.280428ms","start":"2026-06-02T23:52:59.072586Z","end":"2026-06-02T23:52:59.363866Z","steps":["trace[155436192] 'agreement among raft nodes before linearized reading' (duration: 291.239487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:59.364067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.515242ms","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-02T23:52:59.364199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.105611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T23:52:59.364231Z","caller":"traceutil/trace.go:171","msg":"trace[1328735171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1192; }","duration":"644.171223ms","start":"2026-06-02T23:52:58.720051Z","end":"2026-06-02T23:52:59.364222Z","steps":["trace[1328735171] 'agreement among raft nodes before linearized reading' (duration: 644.058769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:59.364257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:58.720034Z","time spent":"644.215565ms","remote":"127.0.0.1:35158","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":"info","ts":"2026-06-02T23:52:59.364103Z","caller":"traceutil/trace.go:171","msg":"trace[2077464079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1192; }","duration":"802.590184ms","start":"2026-06-02T23:52:58.561502Z","end":"2026-06-02T23:52:59.364092Z","steps":["trace[2077464079] 'agreement among raft nodes before linearized reading' (duration: 802.485562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:52:59.364346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:52:58.561483Z","time spent":"802.848422ms","remote":"127.0.0.1:35158","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-02T23:53:17.385636Z","caller":"traceutil/trace.go:171","msg":"trace[2010751621] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1318; }","duration":"152.883558ms","start":"2026-06-02T23:53:17.23272Z","end":"2026-06-02T23:53:17.385603Z","steps":["trace[2010751621] 'read index received' (duration: 152.706333ms)","trace[2010751621] 'applied index is now lower than readState.Index' (duration: 175.775µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:53:17.385798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.056592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:53:17.385835Z","caller":"traceutil/trace.go:171","msg":"trace[237181875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1268; }","duration":"153.137244ms","start":"2026-06-02T23:53:17.232687Z","end":"2026-06-02T23:53:17.385824Z","steps":["trace[237181875] 'agreement among raft nodes before linearized reading' (duration: 153.032372ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:17.386173Z","caller":"traceutil/trace.go:171","msg":"trace[1554750919] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"528.430374ms","start":"2026-06-02T23:53:16.857704Z","end":"2026-06-02T23:53:17.386134Z","steps":["trace[1554750919] 'process raft request' (duration: 527.672184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:17.386369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:16.857677Z","time spent":"528.602438ms","remote":"127.0.0.1:35066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:53:17.891668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.466729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:53:17.891869Z","caller":"traceutil/trace.go:171","msg":"trace[1300043286] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1320; }","duration":"382.076855ms","start":"2026-06-02T23:53:17.509774Z","end":"2026-06-02T23:53:17.891851Z","steps":["trace[1300043286] 'read index received' (duration: 15.367701ms)","trace[1300043286] 'applied index is now lower than readState.Index' (duration: 366.708304ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:17.891974Z","caller":"traceutil/trace.go:171","msg":"trace[1902977775] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"496.355286ms","start":"2026-06-02T23:53:17.395606Z","end":"2026-06-02T23:53:17.891961Z","steps":["trace[1902977775] 'process raft request' (duration: 129.518699ms)","trace[1902977775] 'compare' (duration: 366.359695ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:53:17.892035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:17.395597Z","time spent":"496.407647ms","remote":"127.0.0.1:35292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:53:17.892254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.486205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-02T23:53:17.892299Z","caller":"traceutil/trace.go:171","msg":"trace[22925394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1271; }","duration":"372.594537ms","start":"2026-06-02T23:53:17.519694Z","end":"2026-06-02T23:53:17.892289Z","steps":["trace[22925394] 'agreement among raft nodes before linearized reading' (duration: 372.439613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:17.89233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:17.519675Z","time spent":"372.647429ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T23:53:17.892499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.411681ms","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-02T23:53:17.892572Z","caller":"traceutil/trace.go:171","msg":"trace[1008071448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"370.512083ms","start":"2026-06-02T23:53:17.52205Z","end":"2026-06-02T23:53:17.892562Z","steps":["trace[1008071448] 'agreement among raft nodes before linearized reading' (duration: 370.378289ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:17.892605Z","caller":"traceutil/trace.go:171","msg":"trace[554312605] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"396.923882ms","start":"2026-06-02T23:53:17.495658Z","end":"2026-06-02T23:53:17.892582Z","steps":["trace[554312605] 'process raft request' (duration: 396.123711ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:17.892628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:17.522033Z","time spent":"370.585095ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T23:53:17.892658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.597325ms","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-02T23:53:17.892706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:17.495638Z","time spent":"397.018364ms","remote":"127.0.0.1:35158","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-02T23:53:17.892764Z","caller":"traceutil/trace.go:171","msg":"trace[211663886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"370.705067ms","start":"2026-06-02T23:53:17.522014Z","end":"2026-06-02T23:53:17.892719Z","steps":["trace[211663886] 'agreement among raft nodes before linearized reading' (duration: 370.488012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:17.892805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:17.522003Z","time spent":"370.794189ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T23:53:17.893247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.473251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T23:53:17.893583Z","caller":"traceutil/trace.go:171","msg":"trace[944323000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"383.838351ms","start":"2026-06-02T23:53:17.509733Z","end":"2026-06-02T23:53:17.893571Z","steps":["trace[944323000] 'agreement among raft nodes before linearized reading' (duration: 382.386743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:17.89366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:17.509717Z","time spent":"383.930824ms","remote":"127.0.0.1:35158","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-02T23:53:17.893561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.76391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T23:53:17.894506Z","caller":"traceutil/trace.go:171","msg":"trace[2057151177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"340.960802ms","start":"2026-06-02T23:53:17.553533Z","end":"2026-06-02T23:53:17.894493Z","steps":["trace[2057151177] 'agreement among raft nodes before linearized reading' (duration: 339.078133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:17.894615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:17.55351Z","time spent":"341.091865ms","remote":"127.0.0.1:35158","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-02T23:53:18.637759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.406398ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T23:53:18.637832Z","caller":"traceutil/trace.go:171","msg":"trace[1777977640] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"142.46428ms","start":"2026-06-02T23:53:18.495356Z","end":"2026-06-02T23:53:18.637821Z","steps":["trace[1777977640] 'read index received' (duration: 36.621µs)","trace[1777977640] 'applied index is now lower than readState.Index' (duration: 142.426689ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:53:18.63788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.524002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:53:18.637893Z","caller":"traceutil/trace.go:171","msg":"trace[1942161666] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1276; }","duration":"142.578013ms","start":"2026-06-02T23:53:18.495309Z","end":"2026-06-02T23:53:18.637887Z","steps":["trace[1942161666] 'agreement among raft nodes before linearized reading' (duration: 142.538582ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:19.707231Z","caller":"traceutil/trace.go:171","msg":"trace[1080757611] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"153.411595ms","start":"2026-06-02T23:53:19.553786Z","end":"2026-06-02T23:53:19.707198Z","steps":["trace[1080757611] 'process raft request' (duration: 153.228671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:20.188617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.818617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:53:20.188719Z","caller":"traceutil/trace.go:171","msg":"trace[90355794] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"248.569252ms","start":"2026-06-02T23:53:19.94013Z","end":"2026-06-02T23:53:20.188699Z","steps":["trace[90355794] 'read index received' (duration: 64.591641ms)","trace[90355794] 'applied index is now lower than readState.Index' (duration: 183.976281ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:20.188996Z","caller":"traceutil/trace.go:171","msg":"trace[449566453] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"291.932452ms","start":"2026-06-02T23:53:19.897049Z","end":"2026-06-02T23:53:20.188981Z","steps":["trace[449566453] 'process raft request' (duration: 107.675405ms)","trace[449566453] 'compare' (duration: 183.544839ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:53:20.189259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.134758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-02T23:53:20.189298Z","caller":"traceutil/trace.go:171","msg":"trace[1075739066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1279; }","duration":"249.2143ms","start":"2026-06-02T23:53:19.940075Z","end":"2026-06-02T23:53:20.189289Z","steps":["trace[1075739066] 'agreement among raft nodes before linearized reading' (duration: 249.095727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:20.189532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.090857ms","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-02T23:53:20.189568Z","caller":"traceutil/trace.go:171","msg":"trace[1375423739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1279; }","duration":"244.167619ms","start":"2026-06-02T23:53:19.945392Z","end":"2026-06-02T23:53:20.18956Z","steps":["trace[1375423739] 'agreement among raft nodes before linearized reading' (duration: 244.039865ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:20.189853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.835731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T23:53:20.189892Z","caller":"traceutil/trace.go:171","msg":"trace[1479641322] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1279; }","duration":"243.875692ms","start":"2026-06-02T23:53:19.946007Z","end":"2026-06-02T23:53:20.189883Z","steps":["trace[1479641322] 'agreement among raft nodes before linearized reading' (duration: 243.779318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:20.19006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.23712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T23:53:20.190093Z","caller":"traceutil/trace.go:171","msg":"trace[466180743] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1279; }","duration":"244.282862ms","start":"2026-06-02T23:53:19.945802Z","end":"2026-06-02T23:53:20.190085Z","steps":["trace[466180743] 'agreement among raft nodes before linearized reading' (duration: 244.20457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:20.190252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.583488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T23:53:20.190284Z","caller":"traceutil/trace.go:171","msg":"trace[1870164089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1279; }","duration":"244.632839ms","start":"2026-06-02T23:53:19.945644Z","end":"2026-06-02T23:53:20.190277Z","steps":["trace[1870164089] 'agreement among raft nodes before linearized reading' (duration: 244.555867ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:20.363605Z","caller":"traceutil/trace.go:171","msg":"trace[292760035] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"161.418094ms","start":"2026-06-02T23:53:20.202166Z","end":"2026-06-02T23:53:20.363584Z","steps":["trace[292760035] 'process raft request' (duration: 161.149136ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:20.363624Z","caller":"traceutil/trace.go:171","msg":"trace[1716608095] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"159.540904ms","start":"2026-06-02T23:53:20.204061Z","end":"2026-06-02T23:53:20.363602Z","steps":["trace[1716608095] 'process raft request' (duration: 159.501574ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:20.363653Z","caller":"traceutil/trace.go:171","msg":"trace[1453147408] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"159.597726ms","start":"2026-06-02T23:53:20.20402Z","end":"2026-06-02T23:53:20.363618Z","steps":["trace[1453147408] 'process raft request' (duration: 159.473183ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:20.363744Z","caller":"traceutil/trace.go:171","msg":"trace[903869743] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"159.664728ms","start":"2026-06-02T23:53:20.20407Z","end":"2026-06-02T23:53:20.363734Z","steps":["trace[903869743] 'process raft request' (duration: 159.461043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:29.605912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.958507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:53:29.60604Z","caller":"traceutil/trace.go:171","msg":"trace[1536607149] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1353; }","duration":"574.116771ms","start":"2026-06-02T23:53:29.031895Z","end":"2026-06-02T23:53:29.606012Z","steps":["trace[1536607149] 'count revisions from in-memory index tree' (duration: 573.859794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:29.606135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.58591ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:53:29.60623Z","caller":"traceutil/trace.go:171","msg":"trace[722297918] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1353; }","duration":"744.695023ms","start":"2026-06-02T23:53:28.861521Z","end":"2026-06-02T23:53:29.606216Z","steps":["trace[722297918] 'range keys from in-memory index tree' (duration: 744.572669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:29.605967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.420781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-06-02T23:53:29.606393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:29.031838Z","time spent":"574.526561ms","remote":"127.0.0.1:56282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-06-02T23:53:29.606356Z","caller":"traceutil/trace.go:171","msg":"trace[230673744] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1353; }","duration":"687.868954ms","start":"2026-06-02T23:53:28.918469Z","end":"2026-06-02T23:53:29.606338Z","steps":["trace[230673744] 'range keys from in-memory index tree' (duration: 687.250527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:29.606826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:28.918439Z","time spent":"688.006056ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-02T23:53:30.30094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368099989253264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T23:53:30.767921Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.157092092s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T23:53:30.768358Z","caller":"traceutil/trace.go:171","msg":"trace[144139742] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"1.157594645s","start":"2026-06-02T23:53:29.610744Z","end":"2026-06-02T23:53:30.768339Z","steps":["trace[144139742] 'process raft request' (duration: 1.157471551s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:30.768538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:29.610729Z","time spent":"1.157719158s","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:53:30.778307Z","caller":"traceutil/trace.go:171","msg":"trace[668438588] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"818.944076ms","start":"2026-06-02T23:53:29.959343Z","end":"2026-06-02T23:53:30.778287Z","steps":["trace[668438588] 'process raft request' (duration: 818.792583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:30.778471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:29.959317Z","time spent":"819.08602ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:53:30.778481Z","caller":"traceutil/trace.go:171","msg":"trace[534481061] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1407; }","duration":"978.367817ms","start":"2026-06-02T23:53:29.800085Z","end":"2026-06-02T23:53:30.778453Z","steps":["trace[534481061] 'read index received' (duration: 968.155891ms)","trace[534481061] 'applied index is now lower than readState.Index' (duration: 10.209206ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:53:30.778619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.832481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:53:30.778651Z","caller":"traceutil/trace.go:171","msg":"trace[1561714010] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1356; }","duration":"763.913823ms","start":"2026-06-02T23:53:30.014727Z","end":"2026-06-02T23:53:30.778641Z","steps":["trace[1561714010] 'agreement among raft nodes before linearized reading' (duration: 763.846021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:30.77867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.834069ms","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-02T23:53:30.778696Z","caller":"traceutil/trace.go:171","msg":"trace[1543847252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1356; }","duration":"228.905861ms","start":"2026-06-02T23:53:30.549783Z","end":"2026-06-02T23:53:30.778689Z","steps":["trace[1543847252] 'agreement among raft nodes before linearized reading' (duration: 228.807659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:30.778743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.738335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-02T23:53:30.778768Z","caller":"traceutil/trace.go:171","msg":"trace[1191467989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1356; }","duration":"491.790848ms","start":"2026-06-02T23:53:30.286971Z","end":"2026-06-02T23:53:30.778761Z","steps":["trace[1191467989] 'agreement among raft nodes before linearized reading' (duration: 491.719065ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:30.778789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:30.286956Z","time spent":"491.827879ms","remote":"127.0.0.1:35158","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-02T23:53:30.778757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.663214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-02T23:53:30.778862Z","caller":"traceutil/trace.go:171","msg":"trace[1854003982] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1356; }","duration":"978.808279ms","start":"2026-06-02T23:53:29.800042Z","end":"2026-06-02T23:53:30.77885Z","steps":["trace[1854003982] 'agreement among raft nodes before linearized reading' (duration: 978.510431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:30.778906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:29.800025Z","time spent":"978.872681ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-02T23:53:30.778901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.73748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:53:30.778959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.22355ms","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-02T23:53:30.778921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.972429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T23:53:30.778982Z","caller":"traceutil/trace.go:171","msg":"trace[1321242834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"558.848243ms","start":"2026-06-02T23:53:30.220115Z","end":"2026-06-02T23:53:30.778963Z","steps":["trace[1321242834] 'agreement among raft nodes before linearized reading' (duration: 558.71675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:30.77891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.47209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T23:53:30.779034Z","caller":"traceutil/trace.go:171","msg":"trace[1943343626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1356; }","duration":"359.137542ms","start":"2026-06-02T23:53:30.419877Z","end":"2026-06-02T23:53:30.779015Z","steps":["trace[1943343626] 'agreement among raft nodes before linearized reading' (duration: 358.796863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:30.77905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:30.2201Z","time spent":"558.932935ms","remote":"127.0.0.1:34912","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-02T23:53:30.779095Z","caller":"traceutil/trace.go:171","msg":"trace[1414296483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1356; }","duration":"228.628974ms","start":"2026-06-02T23:53:30.550422Z","end":"2026-06-02T23:53:30.779051Z","steps":["trace[1414296483] 'agreement among raft nodes before linearized reading' (duration: 228.441379ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:30.778982Z","caller":"traceutil/trace.go:171","msg":"trace[257927798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1356; }","duration":"229.270151ms","start":"2026-06-02T23:53:30.549704Z","end":"2026-06-02T23:53:30.778974Z","steps":["trace[257927798] 'agreement among raft nodes before linearized reading' (duration: 229.22003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:30.778676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:30.014693Z","time spent":"763.974745ms","remote":"127.0.0.1:35310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-06-02T23:53:30.77908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:30.419861Z","time spent":"359.211895ms","remote":"127.0.0.1:35158","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":"info","ts":"2026-06-02T23:53:30.779504Z","caller":"traceutil/trace.go:171","msg":"trace[1594119859] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"548.107784ms","start":"2026-06-02T23:53:30.231382Z","end":"2026-06-02T23:53:30.779489Z","steps":["trace[1594119859] 'process raft request' (duration: 546.862491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:30.779606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:30.231363Z","time spent":"548.196165ms","remote":"127.0.0.1:35158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:53:41.042932Z","caller":"traceutil/trace.go:171","msg":"trace[1478136017] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"142.632945ms","start":"2026-06-02T23:53:40.900267Z","end":"2026-06-02T23:53:41.0429Z","steps":["trace[1478136017] 'process raft request' (duration: 70.29776ms)","trace[1478136017] 'compare' (duration: 72.204731ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:41.057124Z","caller":"traceutil/trace.go:171","msg":"trace[616998012] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"155.819108ms","start":"2026-06-02T23:53:40.901283Z","end":"2026-06-02T23:53:41.057103Z","steps":["trace[616998012] 'process raft request' (duration: 155.718306ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:41.057233Z","caller":"traceutil/trace.go:171","msg":"trace[368873476] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"139.438271ms","start":"2026-06-02T23:53:40.917778Z","end":"2026-06-02T23:53:41.057217Z","steps":["trace[368873476] 'process raft request' (duration: 139.258927ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:41.057125Z","caller":"traceutil/trace.go:171","msg":"trace[58885761] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"156.831664ms","start":"2026-06-02T23:53:40.900265Z","end":"2026-06-02T23:53:41.057097Z","steps":["trace[58885761] 'process raft request' (duration: 156.616889ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:49.195386Z","caller":"traceutil/trace.go:171","msg":"trace[894074128] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"208.284163ms","start":"2026-06-02T23:53:48.987076Z","end":"2026-06-02T23:53:49.19536Z","steps":["trace[894074128] 'process raft request' (duration: 208.089909ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:49.219444Z","caller":"traceutil/trace.go:171","msg":"trace[377168303] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"232.30974ms","start":"2026-06-02T23:53:48.987111Z","end":"2026-06-02T23:53:49.219421Z","steps":["trace[377168303] 'process raft request' (duration: 231.900979ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:33.969093Z","caller":"traceutil/trace.go:171","msg":"trace[189916323] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"100.774391ms","start":"2026-06-02T23:55:33.868287Z","end":"2026-06-02T23:55:33.969061Z","steps":["trace[189916323] 'process raft request' (duration: 95.953585ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:33.969074Z","caller":"traceutil/trace.go:171","msg":"trace[1572887189] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"100.679718ms","start":"2026-06-02T23:55:33.868343Z","end":"2026-06-02T23:55:33.969023Z","steps":["trace[1572887189] 'process raft request' (duration: 100.584036ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:57:43.94053Z","caller":"traceutil/trace.go:171","msg":"trace[419823345] transaction","detail":"{read_only:false; response_revision:2552; number_of_response:1; }","duration":"110.972431ms","start":"2026-06-02T23:57:43.829535Z","end":"2026-06-02T23:57:43.940507Z","steps":["trace[419823345] 'process raft request' (duration: 110.804957ms)"],"step_count":1}