{"level":"warn","ts":"2026-05-29T05:46:30.398389Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T05:46:30.398506Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-29T05:46:30.398594Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T05:46:30.39861Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-29T05:46:30.398636Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T05:46:30.39938Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-05-29T05:46:30.399474Z","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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-29T05:46:30.404332Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.637745ms"} {"level":"info","ts":"2026-05-29T05:46:30.416689Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-05-29T05:46:30.416804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T05:46:30.416834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-05-29T05:46:30.416851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T05:46:30.416866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-05-29T05:46:30.416929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-05-29T05:46:30.424106Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T05:46:30.427272Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T05:46:30.42857Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-29T05:46:30.431851Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T05:46:30.432053Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T05:46:30.432303Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T05:46:30.432362Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T05:46:30.432374Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T05:46:30.445823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-05-29T05:46:30.446014Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-29T05:46:30.446565Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T05:46:30.446671Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-29T05:46:30.446716Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-29T05:46:30.446881Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T05:46:30.446921Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T05:46:31.417296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T05:46:31.417395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T05:46:31.417424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-05-29T05:46:31.417447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-05-29T05:46:31.417492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-29T05:46:31.417509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-05-29T05:46:31.417525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-29T05:46:31.418831Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:46:31.41982Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:46:31.419821Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T05:46:31.419934Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:46:31.420175Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T05:46:31.42022Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T05:46:31.420626Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:46:31.420816Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:46:31.420867Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:46:31.422779Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T05:46:31.422959Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-05-29T05:46:33.121444Z","caller":"traceutil/trace.go:171","msg":"trace[1211045270] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"119.055769ms","start":"2026-05-29T05:46:33.00236Z","end":"2026-05-29T05:46:33.121415Z","steps":["trace[1211045270] 'process raft request' (duration: 119.009828ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:33.121598Z","caller":"traceutil/trace.go:171","msg":"trace[80107124] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"166.900066ms","start":"2026-05-29T05:46:32.954596Z","end":"2026-05-29T05:46:33.121496Z","steps":["trace[80107124] 'process raft request' (duration: 125.146273ms)","trace[80107124] 'compare' (duration: 41.416314ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:33.121729Z","caller":"traceutil/trace.go:171","msg":"trace[259333623] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"165.708698ms","start":"2026-05-29T05:46:32.955968Z","end":"2026-05-29T05:46:33.121676Z","steps":["trace[259333623] 'process raft request' (duration: 165.324987ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:33.121634Z","caller":"traceutil/trace.go:171","msg":"trace[1241366344] linearizableReadLoop","detail":"{readStateIndex:37; appliedIndex:32; }","duration":"165.779549ms","start":"2026-05-29T05:46:32.95583Z","end":"2026-05-29T05:46:33.121609Z","steps":["trace[1241366344] 'read index received' (duration: 42.364483ms)","trace[1241366344] 'applied index is now lower than readState.Index' (duration: 123.413616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:33.12178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.921742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:46:33.121892Z","caller":"traceutil/trace.go:171","msg":"trace[346322630] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"165.756108ms","start":"2026-05-29T05:46:32.956121Z","end":"2026-05-29T05:46:33.121878Z","steps":["trace[346322630] 'process raft request' (duration: 165.211024ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:33.122032Z","caller":"traceutil/trace.go:171","msg":"trace[1498714491] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:36; }","duration":"166.067706ms","start":"2026-05-29T05:46:32.955826Z","end":"2026-05-29T05:46:33.121893Z","steps":["trace[1498714491] 'agreement among raft nodes before linearized reading' (duration: 165.857051ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:35.871821Z","caller":"traceutil/trace.go:171","msg":"trace[386858590] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"124.7696ms","start":"2026-05-29T05:46:35.74701Z","end":"2026-05-29T05:46:35.87178Z","steps":["trace[386858590] 'process raft request' (duration: 34.193202ms)","trace[386858590] 'compare' (duration: 90.262351ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:36.035687Z","caller":"traceutil/trace.go:171","msg":"trace[858864224] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"119.173204ms","start":"2026-05-29T05:46:35.916464Z","end":"2026-05-29T05:46:36.035638Z","steps":["trace[858864224] 'process raft request' (duration: 116.299505ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:36.035739Z","caller":"traceutil/trace.go:171","msg":"trace[58341602] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"118.61278ms","start":"2026-05-29T05:46:35.917097Z","end":"2026-05-29T05:46:36.03571Z","steps":["trace[58341602] 'process raft request' (duration: 118.440006ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:36.035668Z","caller":"traceutil/trace.go:171","msg":"trace[85753640] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"105.024223ms","start":"2026-05-29T05:46:35.93061Z","end":"2026-05-29T05:46:36.035635Z","steps":["trace[85753640] 'process raft request' (duration: 104.975722ms)"],"step_count":1} 2026/05/29 05:46:36 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-05-29T05:46:36.427911Z","caller":"traceutil/trace.go:171","msg":"trace[1276495818] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"347.466611ms","start":"2026-05-29T05:46:36.080427Z","end":"2026-05-29T05:46:36.427894Z","steps":["trace[1276495818] 'process raft request' (duration: 347.379479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:36.428133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.416477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:46:36.42822Z","caller":"traceutil/trace.go:171","msg":"trace[530947362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:247; }","duration":"202.55612ms","start":"2026-05-29T05:46:36.225643Z","end":"2026-05-29T05:46:36.428199Z","steps":["trace[530947362] 'agreement among raft nodes before linearized reading' (duration: 202.311864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:36.428395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:36.080401Z","time spent":"347.571543ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:46:36.428647Z","caller":"traceutil/trace.go:171","msg":"trace[701953291] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:251; }","duration":"202.170101ms","start":"2026-05-29T05:46:36.225684Z","end":"2026-05-29T05:46:36.427855Z","steps":["trace[701953291] 'read index received' (duration: 134.355437ms)","trace[701953291] 'applied index is now lower than readState.Index' (duration: 67.813924ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:36.558303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.166424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-29T05:46:36.558525Z","caller":"traceutil/trace.go:171","msg":"trace[1334331384] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:254; }","duration":"104.469352ms","start":"2026-05-29T05:46:36.454016Z","end":"2026-05-29T05:46:36.558485Z","steps":["trace[1334331384] 'agreement among raft nodes before linearized reading' (duration: 103.56527ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:36.812357Z","caller":"traceutil/trace.go:171","msg":"trace[20493838] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"248.635555ms","start":"2026-05-29T05:46:36.563682Z","end":"2026-05-29T05:46:36.812317Z","steps":["trace[20493838] 'process raft request' (duration: 248.468531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:36.81255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.350634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-29T05:46:36.812659Z","caller":"traceutil/trace.go:171","msg":"trace[1005867053] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:260; }","duration":"250.495928ms","start":"2026-05-29T05:46:36.562148Z","end":"2026-05-29T05:46:36.812644Z","steps":["trace[1005867053] 'agreement among raft nodes before linearized reading' (duration: 250.328194ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:36.812731Z","caller":"traceutil/trace.go:171","msg":"trace[1399368138] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"249.084125ms","start":"2026-05-29T05:46:36.563627Z","end":"2026-05-29T05:46:36.812711Z","steps":["trace[1399368138] 'process raft request' (duration: 248.364858ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:36.812425Z","caller":"traceutil/trace.go:171","msg":"trace[1546156865] linearizableReadLoop","detail":"{readStateIndex:268; appliedIndex:261; }","duration":"227.639361ms","start":"2026-05-29T05:46:36.58476Z","end":"2026-05-29T05:46:36.812399Z","steps":["trace[1546156865] 'read index received' (duration: 227.18609ms)","trace[1546156865] 'applied index is now lower than readState.Index' (duration: 452.181µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:36.813006Z","caller":"traceutil/trace.go:171","msg":"trace[1961658018] transaction","detail":"{read_only:false; number_of_response:0; response_revision:259; }","duration":"234.060734ms","start":"2026-05-29T05:46:36.578927Z","end":"2026-05-29T05:46:36.812988Z","steps":["trace[1961658018] 'process raft request' (duration: 233.402879ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:36.813064Z","caller":"traceutil/trace.go:171","msg":"trace[253605294] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"235.557551ms","start":"2026-05-29T05:46:36.577491Z","end":"2026-05-29T05:46:36.813048Z","steps":["trace[253605294] 'process raft request' (duration: 234.707531ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:36.813092Z","caller":"traceutil/trace.go:171","msg":"trace[470753672] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"234.559286ms","start":"2026-05-29T05:46:36.578502Z","end":"2026-05-29T05:46:36.813061Z","steps":["trace[470753672] 'process raft request' (duration: 233.749967ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:36.81299Z","caller":"traceutil/trace.go:171","msg":"trace[954839435] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"234.125327ms","start":"2026-05-29T05:46:36.578826Z","end":"2026-05-29T05:46:36.812951Z","steps":["trace[954839435] 'process raft request' (duration: 233.477991ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:36.813225Z","caller":"traceutil/trace.go:171","msg":"trace[1292000915] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"233.582343ms","start":"2026-05-29T05:46:36.579599Z","end":"2026-05-29T05:46:36.813181Z","steps":["trace[1292000915] 'process raft request' (duration: 232.761514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:36.852854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.252559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-29T05:46:36.852878Z","caller":"traceutil/trace.go:171","msg":"trace[582236450] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"265.056187ms","start":"2026-05-29T05:46:36.587801Z","end":"2026-05-29T05:46:36.852857Z","steps":["trace[582236450] 'process raft request' (duration: 264.855502ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:36.852913Z","caller":"traceutil/trace.go:171","msg":"trace[184511185] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:261; }","duration":"267.350192ms","start":"2026-05-29T05:46:36.585546Z","end":"2026-05-29T05:46:36.852896Z","steps":["trace[184511185] 'agreement among raft nodes before linearized reading' (duration: 267.225539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:36.852909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.119333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-29T05:46:36.852999Z","caller":"traceutil/trace.go:171","msg":"trace[348528956] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:261; }","duration":"266.206746ms","start":"2026-05-29T05:46:36.586758Z","end":"2026-05-29T05:46:36.852965Z","steps":["trace[348528956] 'agreement among raft nodes before linearized reading' (duration: 266.02039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:37.039746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.156801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:46:37.039875Z","caller":"traceutil/trace.go:171","msg":"trace[1749390930] linearizableReadLoop","detail":"{readStateIndex:272; appliedIndex:271; }","duration":"144.962688ms","start":"2026-05-29T05:46:36.894892Z","end":"2026-05-29T05:46:37.039855Z","steps":["trace[1749390930] 'read index received' (duration: 40.402018ms)","trace[1749390930] 'applied index is now lower than readState.Index' (duration: 104.55905ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:37.039949Z","caller":"traceutil/trace.go:171","msg":"trace[592479713] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"182.472406ms","start":"2026-05-29T05:46:36.857429Z","end":"2026-05-29T05:46:37.039901Z","steps":["trace[592479713] 'process raft request' (duration: 77.853505ms)","trace[592479713] 'compare' (duration: 103.930335ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:37.04008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.032349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-29T05:46:37.040118Z","caller":"traceutil/trace.go:171","msg":"trace[2047403782] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:264; }","duration":"183.116961ms","start":"2026-05-29T05:46:36.856992Z","end":"2026-05-29T05:46:37.040109Z","steps":["trace[2047403782] 'agreement among raft nodes before linearized reading' (duration: 182.955907ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:37.074646Z","caller":"traceutil/trace.go:171","msg":"trace[2008407897] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"176.290355ms","start":"2026-05-29T05:46:36.898341Z","end":"2026-05-29T05:46:37.074631Z","steps":["trace[2008407897] 'process raft request' (duration: 176.08749ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:37.074726Z","caller":"traceutil/trace.go:171","msg":"trace[623897310] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"169.401151ms","start":"2026-05-29T05:46:36.905278Z","end":"2026-05-29T05:46:37.074679Z","steps":["trace[623897310] 'process raft request' (duration: 169.291758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:37.074927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.726743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:46:37.07512Z","caller":"traceutil/trace.go:171","msg":"trace[2060483984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:266; }","duration":"168.857226ms","start":"2026-05-29T05:46:36.90614Z","end":"2026-05-29T05:46:37.074997Z","steps":["trace[2060483984] 'agreement among raft nodes before linearized reading' (duration: 168.536839ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:37.358796Z","caller":"traceutil/trace.go:171","msg":"trace[1852775460] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"279.556946ms","start":"2026-05-29T05:46:37.079208Z","end":"2026-05-29T05:46:37.358765Z","steps":["trace[1852775460] 'process raft request' (duration: 279.465944ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:37.359204Z","caller":"traceutil/trace.go:171","msg":"trace[1101066543] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"307.117322ms","start":"2026-05-29T05:46:37.052004Z","end":"2026-05-29T05:46:37.359121Z","steps":["trace[1101066543] 'process raft request' (duration: 227.604567ms)","trace[1101066543] 'compare' (duration: 78.748817ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:37.359299Z","caller":"traceutil/trace.go:171","msg":"trace[901830598] linearizableReadLoop","detail":"{readStateIndex:275; appliedIndex:274; }","duration":"284.650887ms","start":"2026-05-29T05:46:37.074608Z","end":"2026-05-29T05:46:37.359258Z","steps":["trace[901830598] 'read index received' (duration: 205.006208ms)","trace[901830598] 'applied index is now lower than readState.Index' (duration: 79.639548ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:37.359439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.585394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:46:37.359516Z","caller":"traceutil/trace.go:171","msg":"trace[1898985647] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:268; }","duration":"315.696936ms","start":"2026-05-29T05:46:37.043804Z","end":"2026-05-29T05:46:37.359501Z","steps":["trace[1898985647] 'agreement among raft nodes before linearized reading' (duration: 315.578433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:37.359542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:37.043783Z","time spent":"315.753168ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" "} {"level":"warn","ts":"2026-05-29T05:46:37.359574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.004955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-05-29T05:46:37.359624Z","caller":"traceutil/trace.go:171","msg":"trace[605871756] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:268; }","duration":"277.169859ms","start":"2026-05-29T05:46:37.082441Z","end":"2026-05-29T05:46:37.359611Z","steps":["trace[605871756] 'agreement among raft nodes before linearized reading' (duration: 277.026025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:37.359731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:37.05197Z","time spent":"307.352648ms","remote":"127.0.0.1:41614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4183,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:46:37.359716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.568876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:46:37.359872Z","caller":"traceutil/trace.go:171","msg":"trace[56353195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:268; }","duration":"248.785391ms","start":"2026-05-29T05:46:37.111056Z","end":"2026-05-29T05:46:37.359841Z","steps":["trace[56353195] 'agreement among raft nodes before linearized reading' (duration: 248.521665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:37.692059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.106239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:46:37.692414Z","caller":"traceutil/trace.go:171","msg":"trace[348889794] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"253.289899ms","start":"2026-05-29T05:46:37.439091Z","end":"2026-05-29T05:46:37.692381Z","steps":["trace[348889794] 'process raft request' (duration: 115.783541ms)","trace[348889794] 'compare' (duration: 136.956316ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:37.692504Z","caller":"traceutil/trace.go:171","msg":"trace[1105608626] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"247.510392ms","start":"2026-05-29T05:46:37.444973Z","end":"2026-05-29T05:46:37.692484Z","steps":["trace[1105608626] 'process raft request' (duration: 247.290857ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:37.692487Z","caller":"traceutil/trace.go:171","msg":"trace[595293707] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:280; }","duration":"248.231118ms","start":"2026-05-29T05:46:37.444214Z","end":"2026-05-29T05:46:37.692445Z","steps":["trace[595293707] 'read index received' (duration: 110.620907ms)","trace[595293707] 'applied index is now lower than readState.Index' (duration: 137.607261ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:37.692518Z","caller":"traceutil/trace.go:171","msg":"trace[1676773841] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"248.159616ms","start":"2026-05-29T05:46:37.444336Z","end":"2026-05-29T05:46:37.692495Z","steps":["trace[1676773841] 'process raft request' (duration: 247.856079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:37.692702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.462573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:46:37.69274Z","caller":"traceutil/trace.go:171","msg":"trace[573796419] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:275; }","duration":"248.537886ms","start":"2026-05-29T05:46:37.444191Z","end":"2026-05-29T05:46:37.692729Z","steps":["trace[573796419] 'agreement among raft nodes before linearized reading' (duration: 248.422533ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:37.692715Z","caller":"traceutil/trace.go:171","msg":"trace[543178481] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"195.731517ms","start":"2026-05-29T05:46:37.496956Z","end":"2026-05-29T05:46:37.692687Z","steps":["trace[543178481] 'process raft request' (duration: 195.471661ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:37.692778Z","caller":"traceutil/trace.go:171","msg":"trace[381702349] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"194.540928ms","start":"2026-05-29T05:46:37.49821Z","end":"2026-05-29T05:46:37.692751Z","steps":["trace[381702349] 'process raft request' (duration: 194.398515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:37.692849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.500618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-05-29T05:46:37.692907Z","caller":"traceutil/trace.go:171","msg":"trace[1025417279] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:275; }","duration":"197.56183ms","start":"2026-05-29T05:46:37.495332Z","end":"2026-05-29T05:46:37.692894Z","steps":["trace[1025417279] 'agreement among raft nodes before linearized reading' (duration: 197.371355ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:37.692839Z","caller":"traceutil/trace.go:171","msg":"trace[679922084] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"193.807611ms","start":"2026-05-29T05:46:37.499006Z","end":"2026-05-29T05:46:37.692813Z","steps":["trace[679922084] 'process raft request' (duration: 193.667707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:37.954462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.663332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:46:37.954626Z","caller":"traceutil/trace.go:171","msg":"trace[755515191] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"205.411648ms","start":"2026-05-29T05:46:37.749171Z","end":"2026-05-29T05:46:37.954583Z","steps":["trace[755515191] 'process raft request' (duration: 67.553672ms)","trace[755515191] 'compare' (duration: 137.489648ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:37.95467Z","caller":"traceutil/trace.go:171","msg":"trace[456947168] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"200.327627ms","start":"2026-05-29T05:46:37.754323Z","end":"2026-05-29T05:46:37.95465Z","steps":["trace[456947168] 'process raft request' (duration: 200.244825ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:37.954796Z","caller":"traceutil/trace.go:171","msg":"trace[1012661038] linearizableReadLoop","detail":"{readStateIndex:290; appliedIndex:289; }","duration":"201.30546ms","start":"2026-05-29T05:46:37.753467Z","end":"2026-05-29T05:46:37.954772Z","steps":["trace[1012661038] 'read index received' (duration: 63.269739ms)","trace[1012661038] 'applied index is now lower than readState.Index' (duration: 138.032611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:37.955044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.547935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-05-29T05:46:37.955113Z","caller":"traceutil/trace.go:171","msg":"trace[1460694667] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:280; }","duration":"201.665679ms","start":"2026-05-29T05:46:37.753432Z","end":"2026-05-29T05:46:37.955098Z","steps":["trace[1460694667] 'agreement among raft nodes before linearized reading' (duration: 201.471914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:37.955181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.513445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-05-29T05:46:37.955277Z","caller":"traceutil/trace.go:171","msg":"trace[190888584] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:280; }","duration":"201.630757ms","start":"2026-05-29T05:46:37.75359Z","end":"2026-05-29T05:46:37.95522Z","steps":["trace[190888584] 'agreement among raft nodes before linearized reading' (duration: 201.492064ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:39.599872Z","caller":"traceutil/trace.go:171","msg":"trace[1996175772] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"369.991977ms","start":"2026-05-29T05:46:39.229856Z","end":"2026-05-29T05:46:39.599848Z","steps":["trace[1996175772] 'read index received' (duration: 369.735151ms)","trace[1996175772] 'applied index is now lower than readState.Index' (duration: 253.816µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:39.599917Z","caller":"traceutil/trace.go:171","msg":"trace[478185871] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"392.621761ms","start":"2026-05-29T05:46:39.207273Z","end":"2026-05-29T05:46:39.599895Z","steps":["trace[478185871] 'process raft request' (duration: 392.429197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:39.600034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:39.207252Z","time spent":"392.716874ms","remote":"127.0.0.1:41930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:46:39.600261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.377416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-05-29T05:46:39.60027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.494703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-29T05:46:39.600309Z","caller":"traceutil/trace.go:171","msg":"trace[347594993] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:302; }","duration":"370.510689ms","start":"2026-05-29T05:46:39.229785Z","end":"2026-05-29T05:46:39.600296Z","steps":["trace[347594993] 'agreement among raft nodes before linearized reading' (duration: 370.187481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:39.600461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.780856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-29T05:46:39.600529Z","caller":"traceutil/trace.go:171","msg":"trace[366757843] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:302; }","duration":"219.913649ms","start":"2026-05-29T05:46:39.380605Z","end":"2026-05-29T05:46:39.600519Z","steps":["trace[366757843] 'agreement among raft nodes before linearized reading' (duration: 219.797356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:39.600682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.579513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-29T05:46:39.600765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.33771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-29T05:46:39.600839Z","caller":"traceutil/trace.go:171","msg":"trace[894983114] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:302; }","duration":"270.447293ms","start":"2026-05-29T05:46:39.33037Z","end":"2026-05-29T05:46:39.600817Z","steps":["trace[894983114] 'agreement among raft nodes before linearized reading' (duration: 270.31109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:39.600944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:39.229754Z","time spent":"370.777235ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-05-29T05:46:39.600348Z","caller":"traceutil/trace.go:171","msg":"trace[1236574709] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:302; }","duration":"319.656577ms","start":"2026-05-29T05:46:39.280672Z","end":"2026-05-29T05:46:39.600329Z","steps":["trace[1236574709] 'agreement among raft nodes before linearized reading' (duration: 319.453572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:39.600999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.687059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-05-29T05:46:39.601088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:39.280645Z","time spent":"320.425336ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-05-29T05:46:39.601114Z","caller":"traceutil/trace.go:171","msg":"trace[482953463] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:302; }","duration":"119.837463ms","start":"2026-05-29T05:46:39.481256Z","end":"2026-05-29T05:46:39.601094Z","steps":["trace[482953463] 'agreement among raft nodes before linearized reading' (duration: 119.635948ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:39.600736Z","caller":"traceutil/trace.go:171","msg":"trace[1136988732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:302; }","duration":"236.690045ms","start":"2026-05-29T05:46:39.364032Z","end":"2026-05-29T05:46:39.600722Z","steps":["trace[1136988732] 'agreement among raft nodes before linearized reading' (duration: 236.588012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:39.603646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.230525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-29T05:46:39.603706Z","caller":"traceutil/trace.go:171","msg":"trace[1589543620] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:302; }","duration":"173.332537ms","start":"2026-05-29T05:46:39.43036Z","end":"2026-05-29T05:46:39.603692Z","steps":["trace[1589543620] 'agreement among raft nodes before linearized reading' (duration: 173.228335ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:40.178732Z","caller":"traceutil/trace.go:171","msg":"trace[835926832] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:335; }","duration":"255.80598ms","start":"2026-05-29T05:46:39.922904Z","end":"2026-05-29T05:46:40.17871Z","steps":["trace[835926832] 'read index received' (duration: 218.677946ms)","trace[835926832] 'applied index is now lower than readState.Index' (duration: 37.127154ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:40.178852Z","caller":"traceutil/trace.go:171","msg":"trace[812701713] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"260.91718ms","start":"2026-05-29T05:46:39.91792Z","end":"2026-05-29T05:46:40.178837Z","steps":["trace[812701713] 'process raft request' (duration: 223.577631ms)","trace[812701713] 'compare' (duration: 37.023272ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:40.178995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.071006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-wsd6p\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-05-29T05:46:40.179052Z","caller":"traceutil/trace.go:171","msg":"trace[1597531022] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-wsd6p; range_end:; response_count:1; response_revision:326; }","duration":"256.166909ms","start":"2026-05-29T05:46:39.922871Z","end":"2026-05-29T05:46:40.179038Z","steps":["trace[1597531022] 'agreement among raft nodes before linearized reading' (duration: 256.054986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:40.179212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.788568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-29T05:46:40.179322Z","caller":"traceutil/trace.go:171","msg":"trace[961731161] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:326; }","duration":"249.91804ms","start":"2026-05-29T05:46:39.929391Z","end":"2026-05-29T05:46:40.179309Z","steps":["trace[961731161] 'agreement among raft nodes before linearized reading' (duration: 249.726366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:40.179585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.714117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-29T05:46:40.179655Z","caller":"traceutil/trace.go:171","msg":"trace[1971955359] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:326; }","duration":"147.82999ms","start":"2026-05-29T05:46:40.031816Z","end":"2026-05-29T05:46:40.179646Z","steps":["trace[1971955359] 'agreement among raft nodes before linearized reading' (duration: 147.730658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:40.179795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.646528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-29T05:46:40.179868Z","caller":"traceutil/trace.go:171","msg":"trace[1359534824] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:326; }","duration":"198.755741ms","start":"2026-05-29T05:46:39.981099Z","end":"2026-05-29T05:46:40.179855Z","steps":["trace[1359534824] 'agreement among raft nodes before linearized reading' (duration: 198.663749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:40.180043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.426364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:46:40.180084Z","caller":"traceutil/trace.go:171","msg":"trace[1131617184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:326; }","duration":"220.538646ms","start":"2026-05-29T05:46:39.959536Z","end":"2026-05-29T05:46:40.180074Z","steps":["trace[1131617184] 'agreement among raft nodes before linearized reading' (duration: 220.447574ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:40.179226Z","caller":"traceutil/trace.go:171","msg":"trace[1723259435] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"256.63745ms","start":"2026-05-29T05:46:39.922578Z","end":"2026-05-29T05:46:40.179216Z","steps":["trace[1723259435] 'process raft request' (duration: 256.086217ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:41.758568Z","caller":"traceutil/trace.go:171","msg":"trace[160405626] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"113.283496ms","start":"2026-05-29T05:46:41.645223Z","end":"2026-05-29T05:46:41.758507Z","steps":["trace[160405626] 'process raft request' (duration: 112.950369ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:41.913077Z","caller":"traceutil/trace.go:171","msg":"trace[1660374340] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"149.028724ms","start":"2026-05-29T05:46:41.764006Z","end":"2026-05-29T05:46:41.913035Z","steps":["trace[1660374340] 'process raft request' (duration: 109.714592ms)","trace[1660374340] 'compare' (duration: 39.123868ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:43.789186Z","caller":"traceutil/trace.go:171","msg":"trace[1783233867] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"511.891265ms","start":"2026-05-29T05:46:43.277268Z","end":"2026-05-29T05:46:43.789159Z","steps":["trace[1783233867] 'process raft request' (duration: 511.676799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:43.78936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:43.277246Z","time spent":"512.028298ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:46:44.41971Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532707213349931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:46:44.921508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532707213349931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:46:45.153381Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.358399589s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-29T05:46:45.320229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.398558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:46:45.32036Z","caller":"traceutil/trace.go:171","msg":"trace[1762777178] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:372; }","duration":"1.401646434s","start":"2026-05-29T05:46:43.918694Z","end":"2026-05-29T05:46:45.32034Z","steps":["trace[1762777178] 'read index received' (duration: 1.234960949s)","trace[1762777178] 'applied index is now lower than readState.Index' (duration: 166.683805ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:45.320384Z","caller":"traceutil/trace.go:171","msg":"trace[1561803384] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"1.525502194s","start":"2026-05-29T05:46:43.794855Z","end":"2026-05-29T05:46:45.320358Z","steps":["trace[1561803384] 'process raft request' (duration: 1.35887302s)","trace[1561803384] 'compare' (duration: 166.235185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:45.320511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:43.794834Z","time spent":"1.525603827s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:46:45.320524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401824388s","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-05-29T05:46:45.320582Z","caller":"traceutil/trace.go:171","msg":"trace[781184291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:361; }","duration":"1.40193209s","start":"2026-05-29T05:46:43.918635Z","end":"2026-05-29T05:46:45.320567Z","steps":["trace[781184291] 'agreement among raft nodes before linearized reading' (duration: 1.401802898s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:45.320624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:43.918608Z","time spent":"1.402006263s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:46:45.320758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.953566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T05:46:45.321616Z","caller":"traceutil/trace.go:171","msg":"trace[2049722041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:361; }","duration":"742.895139ms","start":"2026-05-29T05:46:44.578686Z","end":"2026-05-29T05:46:45.321581Z","steps":["trace[2049722041] 'agreement among raft nodes before linearized reading' (duration: 741.899956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:45.320767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.816775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-29T05:46:45.321682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:44.578653Z","time spent":"743.012531ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T05:46:45.321732Z","caller":"traceutil/trace.go:171","msg":"trace[1217182765] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:361; }","duration":"815.839858ms","start":"2026-05-29T05:46:44.505875Z","end":"2026-05-29T05:46:45.321715Z","steps":["trace[1217182765] 'agreement among raft nodes before linearized reading' (duration: 814.786114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:45.320777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107432782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-29T05:46:45.321791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:44.505846Z","time spent":"815.930361ms","remote":"127.0.0.1:41470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-05-29T05:46:45.320796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.196404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-29T05:46:45.321969Z","caller":"traceutil/trace.go:171","msg":"trace[1705260510] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:361; }","duration":"456.487564ms","start":"2026-05-29T05:46:44.865468Z","end":"2026-05-29T05:46:45.321956Z","steps":["trace[1705260510] 'agreement among raft nodes before linearized reading' (duration: 455.201634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:45.322006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:44.865435Z","time spent":"456.559596ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4350,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-05-29T05:46:45.322493Z","caller":"traceutil/trace.go:171","msg":"trace[1975244363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:361; }","duration":"1.108578848s","start":"2026-05-29T05:46:44.213213Z","end":"2026-05-29T05:46:45.321792Z","steps":["trace[1975244363] 'agreement among raft nodes before linearized reading' (duration: 1.107350759s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:45.322632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:44.213181Z","time spent":"1.109416747s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:46:46.09603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.054111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:46:46.096183Z","caller":"traceutil/trace.go:171","msg":"trace[1779174000] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"583.689969ms","start":"2026-05-29T05:46:45.51247Z","end":"2026-05-29T05:46:46.09616Z","steps":["trace[1779174000] 'read index received' (duration: 46.121µs)","trace[1779174000] 'applied index is now lower than readState.Index' (duration: 583.642388ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:46.096258Z","caller":"traceutil/trace.go:171","msg":"trace[1693756921] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"725.444119ms","start":"2026-05-29T05:46:45.37077Z","end":"2026-05-29T05:46:46.096214Z","steps":["trace[1693756921] 'process raft request' (duration: 52.073012ms)","trace[1693756921] 'compare' (duration: 672.885166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:46.096449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.978305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-29T05:46:46.096512Z","caller":"traceutil/trace.go:171","msg":"trace[484777707] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:366; }","duration":"584.080457ms","start":"2026-05-29T05:46:45.512414Z","end":"2026-05-29T05:46:46.096495Z","steps":["trace[484777707] 'agreement among raft nodes before linearized reading' (duration: 583.892373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:46.096569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:45.512392Z","time spent":"584.162099ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4350,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-29T05:46:46.096742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:45.370752Z","time spent":"725.717706ms","remote":"127.0.0.1:41470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:46:46.464128Z","caller":"traceutil/trace.go:171","msg":"trace[1183064162] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"359.077052ms","start":"2026-05-29T05:46:46.105018Z","end":"2026-05-29T05:46:46.464095Z","steps":["trace[1183064162] 'process raft request' (duration: 269.119475ms)","trace[1183064162] 'compare' (duration: 89.830444ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:46.464329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:46.105Z","time spent":"359.233585ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:46:47.684308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.545434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:46:47.684446Z","caller":"traceutil/trace.go:171","msg":"trace[2096369547] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"1.207384248s","start":"2026-05-29T05:46:46.477036Z","end":"2026-05-29T05:46:47.68442Z","steps":["trace[2096369547] 'process raft request' (duration: 407.613518ms)","trace[2096369547] 'compare' (duration: 799.139105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:47.684541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:46.477007Z","time spent":"1.20748705s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:46:47.775906Z","caller":"traceutil/trace.go:171","msg":"trace[723441779] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"449.836019ms","start":"2026-05-29T05:46:47.326037Z","end":"2026-05-29T05:46:47.775874Z","steps":["trace[723441779] 'read index received' (duration: 449.600033ms)","trace[723441779] 'applied index is now lower than readState.Index' (duration: 234.616µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:47.776132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.074154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-29T05:46:47.77618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.984625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T05:46:47.776221Z","caller":"traceutil/trace.go:171","msg":"trace[905887681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"358.069517ms","start":"2026-05-29T05:46:47.418142Z","end":"2026-05-29T05:46:47.776212Z","steps":["trace[905887681] 'agreement among raft nodes before linearized reading' (duration: 357.963815ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:47.77618Z","caller":"traceutil/trace.go:171","msg":"trace[1243680043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:369; }","duration":"450.200207ms","start":"2026-05-29T05:46:47.325969Z","end":"2026-05-29T05:46:47.776169Z","steps":["trace[1243680043] 'agreement among raft nodes before linearized reading' (duration: 450.059364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:47.776248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:47.418132Z","time spent":"358.109308ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:46:47.776249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.976358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T05:46:47.776352Z","caller":"traceutil/trace.go:171","msg":"trace[211000614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:369; }","duration":"359.149642ms","start":"2026-05-29T05:46:47.417176Z","end":"2026-05-29T05:46:47.776326Z","steps":["trace[211000614] 'agreement among raft nodes before linearized reading' (duration: 358.936277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:47.776329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.721542ms","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-05-29T05:46:47.776043Z","caller":"traceutil/trace.go:171","msg":"trace[664423326] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"636.490212ms","start":"2026-05-29T05:46:47.13953Z","end":"2026-05-29T05:46:47.77602Z","steps":["trace[664423326] 'process raft request' (duration: 636.186825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:47.776425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:47.417157Z","time spent":"359.255054ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T05:46:47.776433Z","caller":"traceutil/trace.go:171","msg":"trace[2123637127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:369; }","duration":"358.933457ms","start":"2026-05-29T05:46:47.417479Z","end":"2026-05-29T05:46:47.776412Z","steps":["trace[2123637127] 'agreement among raft nodes before linearized reading' (duration: 358.723662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:47.776459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:47.139501Z","time spent":"636.918002ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:46:47.776481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:47.417457Z","time spent":"359.015329ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:46:47.776308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:47.325946Z","time spent":"450.311199ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:47:00.135931Z","caller":"traceutil/trace.go:171","msg":"trace[1135157908] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"168.646559ms","start":"2026-05-29T05:46:59.967259Z","end":"2026-05-29T05:47:00.135906Z","steps":["trace[1135157908] 'process raft request' (duration: 168.599588ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:00.136108Z","caller":"traceutil/trace.go:171","msg":"trace[2106242673] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"168.819373ms","start":"2026-05-29T05:46:59.967245Z","end":"2026-05-29T05:47:00.136064Z","steps":["trace[2106242673] 'process raft request' (duration: 168.462065ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:00.148231Z","caller":"traceutil/trace.go:171","msg":"trace[2116813102] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"180.626331ms","start":"2026-05-29T05:46:59.967582Z","end":"2026-05-29T05:47:00.148208Z","steps":["trace[2116813102] 'process raft request' (duration: 180.505058ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:12.337412Z","caller":"traceutil/trace.go:171","msg":"trace[876721840] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"127.234262ms","start":"2026-05-29T05:47:12.210156Z","end":"2026-05-29T05:47:12.33739Z","steps":["trace[876721840] 'process raft request' (duration: 126.872944ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.123287Z","caller":"traceutil/trace.go:171","msg":"trace[56104375] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"616.774099ms","start":"2026-05-29T05:47:17.506485Z","end":"2026-05-29T05:47:18.123259Z","steps":["trace[56104375] 'process raft request' (duration: 616.525004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:18.123577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:17.506459Z","time spent":"616.982524ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:18.19481Z","caller":"traceutil/trace.go:171","msg":"trace[894650653] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"684.900002ms","start":"2026-05-29T05:47:17.509864Z","end":"2026-05-29T05:47:18.194764Z","steps":["trace[894650653] 'process raft request' (duration: 684.669237ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.194996Z","caller":"traceutil/trace.go:171","msg":"trace[1845935064] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"684.929102ms","start":"2026-05-29T05:47:17.51005Z","end":"2026-05-29T05:47:18.194979Z","steps":["trace[1845935064] 'process raft request' (duration: 684.644966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:18.195078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:17.510042Z","time spent":"685.001923ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:47:18.194992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:17.509837Z","time spent":"685.075605ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:18.195093Z","caller":"traceutil/trace.go:171","msg":"trace[1789033438] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"639.238582ms","start":"2026-05-29T05:47:17.555797Z","end":"2026-05-29T05:47:18.195035Z","steps":["trace[1789033438] 'process raft request' (duration: 639.050898ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.195169Z","caller":"traceutil/trace.go:171","msg":"trace[1697354785] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"658.464782ms","start":"2026-05-29T05:47:17.536542Z","end":"2026-05-29T05:47:18.195007Z","steps":["trace[1697354785] 'process raft request' (duration: 658.260238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:18.195349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:17.555773Z","time spent":"639.471647ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:18.195353Z","caller":"traceutil/trace.go:171","msg":"trace[1929773243] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"638.936916ms","start":"2026-05-29T05:47:17.55638Z","end":"2026-05-29T05:47:18.195317Z","steps":["trace[1929773243] 'process raft request' (duration: 638.511567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:18.195544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:17.556373Z","time spent":"639.081849ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:47:18.19559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:17.536531Z","time spent":"658.8463ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:18.195737Z","caller":"traceutil/trace.go:171","msg":"trace[974627981] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"664.006597ms","start":"2026-05-29T05:47:17.531712Z","end":"2026-05-29T05:47:18.195718Z","steps":["trace[974627981] 'process raft request' (duration: 663.042127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:18.195947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:17.531688Z","time spent":"664.215992ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:18.195849Z","caller":"traceutil/trace.go:171","msg":"trace[1044718626] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"638.001277ms","start":"2026-05-29T05:47:17.55784Z","end":"2026-05-29T05:47:18.195841Z","steps":["trace[1044718626] 'process raft request' (duration: 637.125598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:18.196178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:17.557833Z","time spent":"638.324713ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:18.196001Z","caller":"traceutil/trace.go:171","msg":"trace[382830288] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"636.438245ms","start":"2026-05-29T05:47:17.559554Z","end":"2026-05-29T05:47:18.195992Z","steps":["trace[382830288] 'process raft request' (duration: 636.21278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:18.196343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:17.559548Z","time spent":"636.762471ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:18.196115Z","caller":"traceutil/trace.go:171","msg":"trace[289814089] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"631.519492ms","start":"2026-05-29T05:47:17.564588Z","end":"2026-05-29T05:47:18.196108Z","steps":["trace[289814089] 'process raft request' (duration: 631.257307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:18.196452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:17.564543Z","time spent":"631.89042ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:18.196552Z","caller":"traceutil/trace.go:171","msg":"trace[1689466656] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"513.493967ms","start":"2026-05-29T05:47:17.683049Z","end":"2026-05-29T05:47:18.196543Z","steps":["trace[1689466656] 'process raft request' (duration: 513.313253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:18.196608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:17.682989Z","time spent":"513.60276ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:18.196539Z","caller":"traceutil/trace.go:171","msg":"trace[1048295609] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:529; }","duration":"282.207538ms","start":"2026-05-29T05:47:17.914309Z","end":"2026-05-29T05:47:18.196517Z","steps":["trace[1048295609] 'read index received' (duration: 208.890437ms)","trace[1048295609] 'applied index is now lower than readState.Index' (duration: 73.31588ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:18.196649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.345381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:18.196755Z","caller":"traceutil/trace.go:171","msg":"trace[2029296411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"282.523804ms","start":"2026-05-29T05:47:17.914219Z","end":"2026-05-29T05:47:18.196743Z","steps":["trace[2029296411] 'agreement among raft nodes before linearized reading' (duration: 282.354721ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.196358Z","caller":"traceutil/trace.go:171","msg":"trace[1200205110] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"597.244699ms","start":"2026-05-29T05:47:17.599102Z","end":"2026-05-29T05:47:18.196347Z","steps":["trace[1200205110] 'process raft request' (duration: 596.972703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:18.197265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:17.599092Z","time spent":"598.105046ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:18.331342Z","caller":"traceutil/trace.go:171","msg":"trace[1255334586] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"121.275525ms","start":"2026-05-29T05:47:18.21001Z","end":"2026-05-29T05:47:18.331285Z","steps":["trace[1255334586] 'process raft request' (duration: 65.456627ms)","trace[1255334586] 'compare' (duration: 55.45263ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:18.331356Z","caller":"traceutil/trace.go:171","msg":"trace[766550142] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"121.132892ms","start":"2026-05-29T05:47:18.210183Z","end":"2026-05-29T05:47:18.331316Z","steps":["trace[766550142] 'process raft request' (duration: 121.02548ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.331705Z","caller":"traceutil/trace.go:171","msg":"trace[1990890712] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"119.418828ms","start":"2026-05-29T05:47:18.212262Z","end":"2026-05-29T05:47:18.331681Z","steps":["trace[1990890712] 'process raft request' (duration: 119.103211ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.331805Z","caller":"traceutil/trace.go:171","msg":"trace[2141296923] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:543; }","duration":"109.839428ms","start":"2026-05-29T05:47:18.221932Z","end":"2026-05-29T05:47:18.331771Z","steps":["trace[2141296923] 'read index received' (duration: 53.55094ms)","trace[2141296923] 'applied index is now lower than readState.Index' (duration: 56.287098ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:18.331841Z","caller":"traceutil/trace.go:171","msg":"trace[261796496] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"121.341316ms","start":"2026-05-29T05:47:18.210475Z","end":"2026-05-29T05:47:18.331816Z","steps":["trace[261796496] 'process raft request' (duration: 120.790625ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.33212Z","caller":"traceutil/trace.go:171","msg":"trace[1000465998] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"114.093285ms","start":"2026-05-29T05:47:18.218013Z","end":"2026-05-29T05:47:18.332107Z","steps":["trace[1000465998] 'process raft request' (duration: 113.539634ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.332257Z","caller":"traceutil/trace.go:171","msg":"trace[1234406078] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"116.23182ms","start":"2026-05-29T05:47:18.216011Z","end":"2026-05-29T05:47:18.332243Z","steps":["trace[1234406078] 'process raft request' (duration: 115.445514ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.332385Z","caller":"traceutil/trace.go:171","msg":"trace[1858759945] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"113.913682ms","start":"2026-05-29T05:47:18.218456Z","end":"2026-05-29T05:47:18.33237Z","steps":["trace[1858759945] 'process raft request' (duration: 113.228028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:18.332457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.093662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:18.332518Z","caller":"traceutil/trace.go:171","msg":"trace[1296975293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"134.236504ms","start":"2026-05-29T05:47:18.198262Z","end":"2026-05-29T05:47:18.332499Z","steps":["trace[1296975293] 'agreement among raft nodes before linearized reading' (duration: 134.111942ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.331629Z","caller":"traceutil/trace.go:171","msg":"trace[1592542786] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"121.04333ms","start":"2026-05-29T05:47:18.210552Z","end":"2026-05-29T05:47:18.331595Z","steps":["trace[1592542786] 'process raft request' (duration: 120.752014ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.333693Z","caller":"traceutil/trace.go:171","msg":"trace[1231821038] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"107.371208ms","start":"2026-05-29T05:47:18.22631Z","end":"2026-05-29T05:47:18.333681Z","steps":["trace[1231821038] 'process raft request' (duration: 106.661943ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.646778Z","caller":"traceutil/trace.go:171","msg":"trace[512054912] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"136.061403ms","start":"2026-05-29T05:47:18.510667Z","end":"2026-05-29T05:47:18.646728Z","steps":["trace[512054912] 'process raft request' (duration: 121.268275ms)","trace[512054912] 'compare' (duration: 14.421279ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:18.671603Z","caller":"traceutil/trace.go:171","msg":"trace[1945734993] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"124.596374ms","start":"2026-05-29T05:47:18.546977Z","end":"2026-05-29T05:47:18.671573Z","steps":["trace[1945734993] 'process raft request' (duration: 124.488822ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:18.671602Z","caller":"traceutil/trace.go:171","msg":"trace[1563764772] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"129.20624ms","start":"2026-05-29T05:47:18.542364Z","end":"2026-05-29T05:47:18.67157Z","steps":["trace[1563764772] 'process raft request' (duration: 128.896084ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:19.095457Z","caller":"traceutil/trace.go:171","msg":"trace[66445427] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"258.526268ms","start":"2026-05-29T05:47:18.836885Z","end":"2026-05-29T05:47:19.095412Z","steps":["trace[66445427] 'process raft request' (duration: 202.685153ms)","trace[66445427] 'compare' (duration: 55.530809ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:19.103192Z","caller":"traceutil/trace.go:171","msg":"trace[340433989] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"266.220896ms","start":"2026-05-29T05:47:18.836871Z","end":"2026-05-29T05:47:19.103092Z","steps":["trace[340433989] 'process raft request' (duration: 265.873309ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:19.375065Z","caller":"traceutil/trace.go:171","msg":"trace[242655071] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"280.167226ms","start":"2026-05-29T05:47:19.094863Z","end":"2026-05-29T05:47:19.37503Z","steps":["trace[242655071] 'process raft request' (duration: 192.462041ms)","trace[242655071] 'compare' (duration: 87.337016ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:19.375998Z","caller":"traceutil/trace.go:171","msg":"trace[548423783] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"239.930614ms","start":"2026-05-29T05:47:19.136027Z","end":"2026-05-29T05:47:19.375957Z","steps":["trace[548423783] 'process raft request' (duration: 239.521985ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:19.376002Z","caller":"traceutil/trace.go:171","msg":"trace[13810961] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"237.30089ms","start":"2026-05-29T05:47:19.138667Z","end":"2026-05-29T05:47:19.375968Z","steps":["trace[13810961] 'process raft request' (duration: 237.139637ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:19.607743Z","caller":"traceutil/trace.go:171","msg":"trace[984728469] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"138.586777ms","start":"2026-05-29T05:47:19.469128Z","end":"2026-05-29T05:47:19.607714Z","steps":["trace[984728469] 'process raft request' (duration: 117.569232ms)","trace[984728469] 'compare' (duration: 20.862412ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:19.61027Z","caller":"traceutil/trace.go:171","msg":"trace[403628370] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"131.548642ms","start":"2026-05-29T05:47:19.478696Z","end":"2026-05-29T05:47:19.610244Z","steps":["trace[403628370] 'process raft request' (duration: 131.444399ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:19.610358Z","caller":"traceutil/trace.go:171","msg":"trace[276859919] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"138.564437ms","start":"2026-05-29T05:47:19.471779Z","end":"2026-05-29T05:47:19.610344Z","steps":["trace[276859919] 'process raft request' (duration: 138.211419ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:20.38019Z","caller":"traceutil/trace.go:171","msg":"trace[657885422] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"620.340933ms","start":"2026-05-29T05:47:19.759825Z","end":"2026-05-29T05:47:20.380166Z","steps":["trace[657885422] 'process raft request' (duration: 541.730931ms)","trace[657885422] 'compare' (duration: 78.50755ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:20.380322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:19.759798Z","time spent":"620.454566ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:20.607114Z","caller":"traceutil/trace.go:171","msg":"trace[654425780] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"780.249249ms","start":"2026-05-29T05:47:19.826778Z","end":"2026-05-29T05:47:20.607027Z","steps":["trace[654425780] 'process raft request' (duration: 741.625163ms)","trace[654425780] 'compare' (duration: 38.205758ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:20.607295Z","caller":"traceutil/trace.go:171","msg":"trace[590661736] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"773.762445ms","start":"2026-05-29T05:47:19.833495Z","end":"2026-05-29T05:47:20.607258Z","steps":["trace[590661736] 'process raft request' (duration: 773.422038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:20.607322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:19.82675Z","time spent":"780.485585ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:47:20.607516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:19.828283Z","time spent":"779.110246ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:20.675044Z","caller":"traceutil/trace.go:171","msg":"trace[862759410] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"272.949952ms","start":"2026-05-29T05:47:20.402069Z","end":"2026-05-29T05:47:20.675019Z","steps":["trace[862759410] 'process raft request' (duration: 272.783389ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:20.675315Z","caller":"traceutil/trace.go:171","msg":"trace[1693715390] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:595; }","duration":"760.642461ms","start":"2026-05-29T05:47:19.914655Z","end":"2026-05-29T05:47:20.675297Z","steps":["trace[1693715390] 'read index received' (duration: 387.079942ms)","trace[1693715390] 'applied index is now lower than readState.Index' (duration: 373.561129ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:20.675433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.770544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:20.675462Z","caller":"traceutil/trace.go:171","msg":"trace[1064108865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"760.821465ms","start":"2026-05-29T05:47:19.914635Z","end":"2026-05-29T05:47:20.675457Z","steps":["trace[1064108865] 'agreement among raft nodes before linearized reading' (duration: 760.751074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:20.675487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:19.914596Z","time spent":"760.885027ms","remote":"127.0.0.1:41424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:47:20.926609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.891809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:47:20.926896Z","caller":"traceutil/trace.go:171","msg":"trace[652987997] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"251.459719ms","start":"2026-05-29T05:47:20.675371Z","end":"2026-05-29T05:47:20.926831Z","steps":["trace[652987997] 'read index received' (duration: 76.229843ms)","trace[652987997] 'applied index is now lower than readState.Index' (duration: 175.227986ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:20.926993Z","caller":"traceutil/trace.go:171","msg":"trace[482353083] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"257.731648ms","start":"2026-05-29T05:47:20.669235Z","end":"2026-05-29T05:47:20.926967Z","steps":["trace[482353083] 'process raft request' (duration: 82.360689ms)","trace[482353083] 'compare' (duration: 174.677924ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:20.927071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.265015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:47:20.927267Z","caller":"traceutil/trace.go:171","msg":"trace[1654807598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"456.49391ms","start":"2026-05-29T05:47:20.470749Z","end":"2026-05-29T05:47:20.927243Z","steps":["trace[1654807598] 'agreement among raft nodes before linearized reading' (duration: 456.132323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:20.927327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:20.470737Z","time spent":"456.577021ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:47:20.927327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.735469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-05-29T05:47:20.927433Z","caller":"traceutil/trace.go:171","msg":"trace[640314450] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:577; }","duration":"272.909252ms","start":"2026-05-29T05:47:20.6545Z","end":"2026-05-29T05:47:20.927409Z","steps":["trace[640314450] 'agreement among raft nodes before linearized reading' (duration: 272.527345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:20.92743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.32538ms","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-05-29T05:47:20.927505Z","caller":"traceutil/trace.go:171","msg":"trace[1593344745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"587.445012ms","start":"2026-05-29T05:47:20.340038Z","end":"2026-05-29T05:47:20.927483Z","steps":["trace[1593344745] 'agreement among raft nodes before linearized reading' (duration: 587.197727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:20.927581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:20.340011Z","time spent":"587.551524ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:47:20.927531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.398735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-29T05:47:20.927935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.989964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:47:20.927988Z","caller":"traceutil/trace.go:171","msg":"trace[1587532184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"463.045515ms","start":"2026-05-29T05:47:20.46493Z","end":"2026-05-29T05:47:20.927975Z","steps":["trace[1587532184] 'agreement among raft nodes before linearized reading' (duration: 462.070765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:20.928023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:20.464884Z","time spent":"463.129947ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T05:47:20.928111Z","caller":"traceutil/trace.go:171","msg":"trace[32860141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"467.895946ms","start":"2026-05-29T05:47:20.460063Z","end":"2026-05-29T05:47:20.927959Z","steps":["trace[32860141] 'agreement among raft nodes before linearized reading' (duration: 467.057469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:20.928218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:20.460034Z","time spent":"468.16471ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T05:47:20.96344Z","caller":"traceutil/trace.go:171","msg":"trace[1870222997] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"273.596706ms","start":"2026-05-29T05:47:20.689818Z","end":"2026-05-29T05:47:20.963415Z","steps":["trace[1870222997] 'process raft request' (duration: 273.461302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:21.464379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532707213350332,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:47:21.571195Z","caller":"traceutil/trace.go:171","msg":"trace[1093873030] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"614.663923ms","start":"2026-05-29T05:47:20.956432Z","end":"2026-05-29T05:47:21.571096Z","steps":["trace[1093873030] 'process raft request' (duration: 614.531371ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:21.571382Z","caller":"traceutil/trace.go:171","msg":"trace[72619013] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"638.239241ms","start":"2026-05-29T05:47:20.933081Z","end":"2026-05-29T05:47:21.57132Z","steps":["trace[72619013] 'process raft request' (duration: 637.587817ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:21.571425Z","caller":"traceutil/trace.go:171","msg":"trace[34762562] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"635.894792ms","start":"2026-05-29T05:47:20.935491Z","end":"2026-05-29T05:47:21.571386Z","steps":["trace[34762562] 'process raft request' (duration: 635.385421ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:21.571493Z","caller":"traceutil/trace.go:171","msg":"trace[1894114374] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"638.446805ms","start":"2026-05-29T05:47:20.933008Z","end":"2026-05-29T05:47:21.571454Z","steps":["trace[1894114374] 'process raft request' (duration: 556.752372ms)","trace[1894114374] 'compare' (duration: 80.376235ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:21.571503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:20.956411Z","time spent":"614.95731ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:21.571486Z","caller":"traceutil/trace.go:171","msg":"trace[1220641401] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"637.209959ms","start":"2026-05-29T05:47:20.934243Z","end":"2026-05-29T05:47:21.571453Z","steps":["trace[1220641401] 'process raft request' (duration: 636.580576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:21.571814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:20.935473Z","time spent":"636.226078ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:47:21.571824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:20.932982Z","time spent":"638.745021ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:47:21.571819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:20.933052Z","time spent":"638.437914ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:47:21.571881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:20.934215Z","time spent":"637.561707ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:21.705371Z","caller":"traceutil/trace.go:171","msg":"trace[1283848033] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:602; }","duration":"741.896801ms","start":"2026-05-29T05:47:20.96342Z","end":"2026-05-29T05:47:21.705317Z","steps":["trace[1283848033] 'read index received' (duration: 526.438396ms)","trace[1283848033] 'applied index is now lower than readState.Index' (duration: 215.456055ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:21.705473Z","caller":"traceutil/trace.go:171","msg":"trace[61682184] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"611.920255ms","start":"2026-05-29T05:47:21.093523Z","end":"2026-05-29T05:47:21.705443Z","steps":["trace[61682184] 'process raft request' (duration: 597.726173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:21.705616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:21.093494Z","time spent":"612.038478ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:47:21.705799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.95504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-05-29T05:47:21.705903Z","caller":"traceutil/trace.go:171","msg":"trace[1959818295] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:584; }","duration":"766.113983ms","start":"2026-05-29T05:47:20.939763Z","end":"2026-05-29T05:47:21.705877Z","steps":["trace[1959818295] 'agreement among raft nodes before linearized reading' (duration: 765.710963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:21.705962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:20.939726Z","time spent":"766.225685ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10894,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-05-29T05:47:21.715009Z","caller":"traceutil/trace.go:171","msg":"trace[138028314] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"125.294489ms","start":"2026-05-29T05:47:21.589652Z","end":"2026-05-29T05:47:21.714947Z","steps":["trace[138028314] 'process raft request' (duration: 125.015963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:21.715048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.342916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:21.715167Z","caller":"traceutil/trace.go:171","msg":"trace[1358357747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"750.503279ms","start":"2026-05-29T05:47:20.964622Z","end":"2026-05-29T05:47:21.715126Z","steps":["trace[1358357747] 'agreement among raft nodes before linearized reading' (duration: 750.263465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:21.715217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:20.9646Z","time spent":"750.608601ms","remote":"127.0.0.1:41424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T05:47:24.000639Z","caller":"traceutil/trace.go:171","msg":"trace[1430786602] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"423.989475ms","start":"2026-05-29T05:47:23.576625Z","end":"2026-05-29T05:47:24.000615Z","steps":["trace[1430786602] 'read index received' (duration: 423.75216ms)","trace[1430786602] 'applied index is now lower than readState.Index' (duration: 236.025µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:24.000809Z","caller":"traceutil/trace.go:171","msg":"trace[609506581] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"602.690044ms","start":"2026-05-29T05:47:23.398101Z","end":"2026-05-29T05:47:24.000791Z","steps":["trace[609506581] 'process raft request' (duration: 602.322207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:24.000922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:23.398057Z","time spent":"602.798007ms","remote":"127.0.0.1:41550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:47:24.001056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.425023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:47:24.001127Z","caller":"traceutil/trace.go:171","msg":"trace[1023984471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"424.518985ms","start":"2026-05-29T05:47:23.576571Z","end":"2026-05-29T05:47:24.00109Z","steps":["trace[1023984471] 'agreement among raft nodes before linearized reading' (duration: 424.2219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:24.001374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.259119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T05:47:24.001429Z","caller":"traceutil/trace.go:171","msg":"trace[2138299222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"424.334061ms","start":"2026-05-29T05:47:23.577082Z","end":"2026-05-29T05:47:24.001416Z","steps":["trace[2138299222] 'agreement among raft nodes before linearized reading' (duration: 424.182978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:24.001465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:23.577072Z","time spent":"424.382792ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:47:24.001488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.148747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T05:47:24.00156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:23.576548Z","time spent":"424.618378ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:47:24.001603Z","caller":"traceutil/trace.go:171","msg":"trace[721867610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"424.343961ms","start":"2026-05-29T05:47:23.577233Z","end":"2026-05-29T05:47:24.001577Z","steps":["trace[721867610] 'agreement among raft nodes before linearized reading' (duration: 423.912853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:24.001678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:23.577196Z","time spent":"424.467084ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:47:24.001967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.142978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:47:24.002015Z","caller":"traceutil/trace.go:171","msg":"trace[317487711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"425.262771ms","start":"2026-05-29T05:47:23.576741Z","end":"2026-05-29T05:47:24.002004Z","steps":["trace[317487711] 'agreement among raft nodes before linearized reading' (duration: 424.369963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:24.002126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:23.576687Z","time spent":"425.427814ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T05:47:24.517845Z","caller":"traceutil/trace.go:171","msg":"trace[1616818326] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"509.770356ms","start":"2026-05-29T05:47:24.008042Z","end":"2026-05-29T05:47:24.517812Z","steps":["trace[1616818326] 'process raft request' (duration: 509.669084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:24.518045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:24.008029Z","time spent":"509.931609ms","remote":"127.0.0.1:41542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:24.518223Z","caller":"traceutil/trace.go:171","msg":"trace[352771503] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"510.618833ms","start":"2026-05-29T05:47:24.007573Z","end":"2026-05-29T05:47:24.518191Z","steps":["trace[352771503] 'process raft request' (duration: 510.075752ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:24.518314Z","caller":"traceutil/trace.go:171","msg":"trace[1765257077] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"506.364857ms","start":"2026-05-29T05:47:24.011924Z","end":"2026-05-29T05:47:24.518289Z","steps":["trace[1765257077] 'process raft request' (duration: 505.838325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:24.518523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:24.011914Z","time spent":"506.496359ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:24.518334Z","caller":"traceutil/trace.go:171","msg":"trace[1538524533] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"511.376919ms","start":"2026-05-29T05:47:24.006916Z","end":"2026-05-29T05:47:24.518293Z","steps":["trace[1538524533] 'process raft request' (duration: 486.830077ms)","trace[1538524533] 'compare' (duration: 23.452699ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:24.518602Z","caller":"traceutil/trace.go:171","msg":"trace[1657419625] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"511.117564ms","start":"2026-05-29T05:47:24.007464Z","end":"2026-05-29T05:47:24.518581Z","steps":["trace[1657419625] 'process raft request' (duration: 510.088042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:24.518712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:24.007451Z","time spent":"511.190505ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:47:24.518832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:24.007542Z","time spent":"510.814358ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:24.519031Z","caller":"traceutil/trace.go:171","msg":"trace[745182022] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"506.862536ms","start":"2026-05-29T05:47:24.012051Z","end":"2026-05-29T05:47:24.518914Z","steps":["trace[745182022] 'process raft request' (duration: 505.949387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:24.519266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:24.012023Z","time spent":"507.176433ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:24.519203Z","caller":"traceutil/trace.go:171","msg":"trace[26479558] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"249.900396ms","start":"2026-05-29T05:47:24.269287Z","end":"2026-05-29T05:47:24.519188Z","steps":["trace[26479558] 'process raft request' (duration: 249.442447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:24.519564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:24.006878Z","time spent":"511.966791ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:24.523321Z","caller":"traceutil/trace.go:171","msg":"trace[691265348] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"126.970684ms","start":"2026-05-29T05:47:24.396314Z","end":"2026-05-29T05:47:24.523285Z","steps":["trace[691265348] 'process raft request' (duration: 125.480354ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:27.374485Z","caller":"traceutil/trace.go:171","msg":"trace[1204146393] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"373.386975ms","start":"2026-05-29T05:47:27.001067Z","end":"2026-05-29T05:47:27.374454Z","steps":["trace[1204146393] 'process raft request' (duration: 372.714282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:27.37466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:27.001034Z","time spent":"373.532148ms","remote":"127.0.0.1:41536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036253,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:27.882817Z","caller":"traceutil/trace.go:171","msg":"trace[375944554] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"253.331641ms","start":"2026-05-29T05:47:27.629455Z","end":"2026-05-29T05:47:27.882787Z","steps":["trace[375944554] 'read index received' (duration: 253.073515ms)","trace[375944554] 'applied index is now lower than readState.Index' (duration: 256.826µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:27.883284Z","caller":"traceutil/trace.go:171","msg":"trace[421327139] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"436.941596ms","start":"2026-05-29T05:47:27.446139Z","end":"2026-05-29T05:47:27.883081Z","steps":["trace[421327139] 'process raft request' (duration: 436.478005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:27.883695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:27.446116Z","time spent":"437.473126ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:47:27.883413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.937402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d2tzs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-29T05:47:27.883941Z","caller":"traceutil/trace.go:171","msg":"trace[1985591138] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d2tzs; range_end:; response_count:1; response_revision:617; }","duration":"254.522464ms","start":"2026-05-29T05:47:27.629396Z","end":"2026-05-29T05:47:27.883919Z","steps":["trace[1985591138] 'agreement among raft nodes before linearized reading' (duration: 253.511164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:28.411271Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532707213350423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:47:28.912884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532707213350423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:47:29.374692Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.474397794s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:47:29.408631Z","caller":"traceutil/trace.go:171","msg":"trace[2009359552] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.508541743s","start":"2026-05-29T05:47:27.900065Z","end":"2026-05-29T05:47:29.408606Z","steps":["trace[2009359552] 'process raft request' (duration: 1.474809482s)","trace[2009359552] 'compare' (duration: 33.615088ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:29.408783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:27.900014Z","time spent":"1.508678465s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:47:29.912028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000847536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-29T05:47:29.912232Z","caller":"traceutil/trace.go:171","msg":"trace[1264677425] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00107261s","start":"2026-05-29T05:47:27.911128Z","end":"2026-05-29T05:47:29.912201Z","steps":["trace[1264677425] 'agreement among raft nodes before linearized reading' (duration: 2.000841815s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:29.912315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:27.911079Z","time spent":"2.001217143s","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/29 05:47:29 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T05:47:31.365198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999881165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-29T05:47:31.365307Z","caller":"traceutil/trace.go:171","msg":"trace[180735393] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000048798s","start":"2026-05-29T05:47:29.365227Z","end":"2026-05-29T05:47:31.365275Z","steps":["trace[180735393] 'agreement among raft nodes before linearized reading' (duration: 1.999918956s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:31.365364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:29.365202Z","time spent":"2.00015066s","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/29 05:47:31 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T05:47:31.914686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001938448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-29T05:47:31.915531Z","caller":"traceutil/trace.go:171","msg":"trace[2082512579] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.002801305s","start":"2026-05-29T05:47:29.912693Z","end":"2026-05-29T05:47:31.915494Z","steps":["trace[2082512579] 'agreement among raft nodes before linearized reading' (duration: 2.001932397s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:31.915607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:29.912612Z","time spent":"2.002978159s","remote":"127.0.0.1:41424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/29 05:47:31 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T05:47:31.944636Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.568203301s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:47:31.958281Z","caller":"traceutil/trace.go:171","msg":"trace[1049964884] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"3.6652436s","start":"2026-05-29T05:47:28.292988Z","end":"2026-05-29T05:47:31.958232Z","steps":["trace[1049964884] 'process raft request' (duration: 3.652139827s)","trace[1049964884] 'compare' (duration: 11.360578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:31.958533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:28.292952Z","time spent":"3.665425363s","remote":"127.0.0.1:41536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:31.958916Z","caller":"traceutil/trace.go:171","msg":"trace[365016420] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"3.275858758s","start":"2026-05-29T05:47:28.683015Z","end":"2026-05-29T05:47:31.958874Z","steps":["trace[365016420] 'process raft request' (duration: 3.275206874s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:31.959238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:28.682995Z","time spent":"3.276061983s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:31.958996Z","caller":"traceutil/trace.go:171","msg":"trace[2133070584] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:642; }","duration":"4.047796788s","start":"2026-05-29T05:47:27.911162Z","end":"2026-05-29T05:47:31.958959Z","steps":["trace[2133070584] 'read index received' (duration: 1.463782928s)","trace[2133070584] 'applied index is now lower than readState.Index' (duration: 2.5840115s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:32.288459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.703377ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-29T05:47:32.288915Z","caller":"traceutil/trace.go:171","msg":"trace[362276616] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"329.33125ms","start":"2026-05-29T05:47:31.959535Z","end":"2026-05-29T05:47:32.288867Z","steps":["trace[362276616] 'read index received' (duration: 2.930439ms)","trace[362276616] 'applied index is now lower than readState.Index' (duration: 326.395351ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:32.288902Z","caller":"traceutil/trace.go:171","msg":"trace[42761454] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"919.391355ms","start":"2026-05-29T05:47:31.369441Z","end":"2026-05-29T05:47:32.288833Z","steps":["trace[42761454] 'process raft request' (duration: 919.11669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:32.289306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:31.369402Z","time spent":"919.762952ms","remote":"127.0.0.1:41516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:47:32.288603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.708622ms","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-05-29T05:47:32.289514Z","caller":"traceutil/trace.go:171","msg":"trace[1670352091] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:620; }","duration":"133.648461ms","start":"2026-05-29T05:47:32.155841Z","end":"2026-05-29T05:47:32.28949Z","steps":["trace[1670352091] 'range keys from in-memory index tree' (duration: 132.687561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:32.31751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.081991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:47:32.3178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.785932583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:47:32.31785Z","caller":"traceutil/trace.go:171","msg":"trace[1561734031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"392.303225ms","start":"2026-05-29T05:47:31.925327Z","end":"2026-05-29T05:47:32.31763Z","steps":["trace[1561734031] 'agreement among raft nodes before linearized reading' (duration: 363.68763ms)","trace[1561734031] 'range keys from in-memory index tree' (duration: 28.399321ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:32.317902Z","caller":"traceutil/trace.go:171","msg":"trace[195565726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"3.786095466s","start":"2026-05-29T05:47:28.53178Z","end":"2026-05-29T05:47:32.317875Z","steps":["trace[195565726] 'agreement among raft nodes before linearized reading' (duration: 3.757283047s)","trace[195565726] 'range keys from in-memory index tree' (duration: 28.554384ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:32.317979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:28.531755Z","time spent":"3.786208108s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:47:32.318113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:31.925308Z","time spent":"392.594981ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:47:32.318101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.781595163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-29T05:47:32.317985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.782457841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T05:47:32.317803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.781825429s","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-05-29T05:47:32.318238Z","caller":"traceutil/trace.go:171","msg":"trace[498528341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"3.781770497s","start":"2026-05-29T05:47:28.536437Z","end":"2026-05-29T05:47:32.318207Z","steps":["trace[498528341] 'agreement among raft nodes before linearized reading' (duration: 3.752555141s)","trace[498528341] 'range keys from in-memory index tree' (duration: 28.908861ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:32.318294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:28.536415Z","time spent":"3.781867099s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T05:47:32.318289Z","caller":"traceutil/trace.go:171","msg":"trace[1964209479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"3.782808388s","start":"2026-05-29T05:47:28.535412Z","end":"2026-05-29T05:47:32.31822Z","steps":["trace[1964209479] 'agreement among raft nodes before linearized reading' (duration: 3.753643572s)","trace[1964209479] 'range keys from in-memory index tree' (duration: 28.776059ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:32.3185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:28.535384Z","time spent":"3.783052224s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T05:47:32.318288Z","caller":"traceutil/trace.go:171","msg":"trace[936285508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"3.782345798s","start":"2026-05-29T05:47:28.535917Z","end":"2026-05-29T05:47:32.318263Z","steps":["trace[936285508] 'agreement among raft nodes before linearized reading' (duration: 3.753178733s)","trace[936285508] 'range keys from in-memory index tree' (duration: 28.555444ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:32.318675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:28.535907Z","time spent":"3.782730306s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T05:47:32.318695Z","caller":"traceutil/trace.go:171","msg":"trace[727916426] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"306.295186ms","start":"2026-05-29T05:47:32.012379Z","end":"2026-05-29T05:47:32.318674Z","steps":["trace[727916426] 'process raft request' (duration: 276.473457ms)","trace[727916426] 'compare' (duration: 28.575244ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:32.318804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:32.012345Z","time spent":"306.393758ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:32.893544Z","caller":"traceutil/trace.go:171","msg":"trace[1082398162] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"234.239169ms","start":"2026-05-29T05:47:32.659278Z","end":"2026-05-29T05:47:32.893517Z","steps":["trace[1082398162] 'process raft request' (duration: 234.005955ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:32.997097Z","caller":"traceutil/trace.go:171","msg":"trace[2048216051] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"229.477044ms","start":"2026-05-29T05:47:32.767594Z","end":"2026-05-29T05:47:32.997071Z","steps":["trace[2048216051] 'process raft request' (duration: 228.897163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:33.367947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.278138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:47:33.368133Z","caller":"traceutil/trace.go:171","msg":"trace[1253019953] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"184.839391ms","start":"2026-05-29T05:47:33.183281Z","end":"2026-05-29T05:47:33.36812Z","steps":["trace[1253019953] 'process raft request' (duration: 184.80135ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:33.368278Z","caller":"traceutil/trace.go:171","msg":"trace[1008064083] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"188.75456ms","start":"2026-05-29T05:47:33.179479Z","end":"2026-05-29T05:47:33.368234Z","steps":["trace[1008064083] 'process raft request' (duration: 65.128705ms)","trace[1008064083] 'compare' (duration: 123.162786ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:33.368317Z","caller":"traceutil/trace.go:171","msg":"trace[1864876367] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"185.450444ms","start":"2026-05-29T05:47:33.18283Z","end":"2026-05-29T05:47:33.36828Z","steps":["trace[1864876367] 'process raft request' (duration: 185.206729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:35.662364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.658166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:47:35.662464Z","caller":"traceutil/trace.go:171","msg":"trace[2100109698] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"452.536504ms","start":"2026-05-29T05:47:35.209913Z","end":"2026-05-29T05:47:35.662449Z","steps":["trace[2100109698] 'read index received' (duration: 67.342µs)","trace[2100109698] 'applied index is now lower than readState.Index' (duration: 452.467722ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:35.662563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.657786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:35.662589Z","caller":"traceutil/trace.go:171","msg":"trace[1977937667] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:649; }","duration":"452.734357ms","start":"2026-05-29T05:47:35.209846Z","end":"2026-05-29T05:47:35.66258Z","steps":["trace[1977937667] 'agreement among raft nodes before linearized reading' (duration: 452.641786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:35.662618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:35.20982Z","time spent":"452.788568ms","remote":"127.0.0.1:41674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:47:35.662617Z","caller":"traceutil/trace.go:171","msg":"trace[1901602762] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"822.21525ms","start":"2026-05-29T05:47:34.840366Z","end":"2026-05-29T05:47:35.662581Z","steps":["trace[1901602762] 'process raft request' (duration: 215.277139ms)","trace[1901602762] 'compare' (duration: 606.435721ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:35.662683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:34.840349Z","time spent":"822.302483ms","remote":"127.0.0.1:41516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:36.151609Z","caller":"traceutil/trace.go:171","msg":"trace[1182540186] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"127.17858ms","start":"2026-05-29T05:47:36.024409Z","end":"2026-05-29T05:47:36.151588Z","steps":["trace[1182540186] 'process raft request' (duration: 120.079779ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:36.151911Z","caller":"traceutil/trace.go:171","msg":"trace[1796714844] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"126.572098ms","start":"2026-05-29T05:47:36.02532Z","end":"2026-05-29T05:47:36.151892Z","steps":["trace[1796714844] 'process raft request' (duration: 126.230941ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:36.152131Z","caller":"traceutil/trace.go:171","msg":"trace[1088042818] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:689; }","duration":"109.733824ms","start":"2026-05-29T05:47:36.042083Z","end":"2026-05-29T05:47:36.151816Z","steps":["trace[1088042818] 'read index received' (duration: 102.375898ms)","trace[1088042818] 'applied index is now lower than readState.Index' (duration: 7.356196ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:36.152383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.271855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v2alpha1.cilium.io\" ","response":"range_response_count:1 size:965"} {"level":"info","ts":"2026-05-29T05:47:36.152434Z","caller":"traceutil/trace.go:171","msg":"trace[562399299] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v2alpha1.cilium.io; range_end:; response_count:1; response_revision:662; }","duration":"110.339956ms","start":"2026-05-29T05:47:36.042075Z","end":"2026-05-29T05:47:36.152415Z","steps":["trace[562399299] 'agreement among raft nodes before linearized reading' (duration: 110.111861ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:36.260557Z","caller":"traceutil/trace.go:171","msg":"trace[1174695426] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"100.686054ms","start":"2026-05-29T05:47:36.159834Z","end":"2026-05-29T05:47:36.26052Z","steps":["trace[1174695426] 'process raft request' (duration: 97.4862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:36.584609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.029971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:47:36.584699Z","caller":"traceutil/trace.go:171","msg":"trace[1222997477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:668; }","duration":"197.172744ms","start":"2026-05-29T05:47:36.387503Z","end":"2026-05-29T05:47:36.584676Z","steps":["trace[1222997477] 'range keys from in-memory index tree' (duration: 196.815927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:36.585077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.672657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:47:36.585188Z","caller":"traceutil/trace.go:171","msg":"trace[445939961] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:668; }","duration":"154.881371ms","start":"2026-05-29T05:47:36.430288Z","end":"2026-05-29T05:47:36.585169Z","steps":["trace[445939961] 'count revisions from in-memory index tree' (duration: 154.573305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:36.585228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.368707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:47:36.58528Z","caller":"traceutil/trace.go:171","msg":"trace[610415648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"186.442149ms","start":"2026-05-29T05:47:36.398827Z","end":"2026-05-29T05:47:36.585269Z","steps":["trace[610415648] 'range keys from in-memory index tree' (duration: 186.278776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:36.585458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.153664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T05:47:36.585495Z","caller":"traceutil/trace.go:171","msg":"trace[1370883188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:668; }","duration":"187.209835ms","start":"2026-05-29T05:47:36.398275Z","end":"2026-05-29T05:47:36.585485Z","steps":["trace[1370883188] 'range keys from in-memory index tree' (duration: 187.062182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:36.586465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.956799ms","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-05-29T05:47:36.58663Z","caller":"traceutil/trace.go:171","msg":"trace[1954989094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:668; }","duration":"187.139474ms","start":"2026-05-29T05:47:36.399476Z","end":"2026-05-29T05:47:36.586615Z","steps":["trace[1954989094] 'range keys from in-memory index tree' (duration: 186.035121ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:36.616786Z","caller":"traceutil/trace.go:171","msg":"trace[1208577469] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"128.63764ms","start":"2026-05-29T05:47:36.48813Z","end":"2026-05-29T05:47:36.616767Z","steps":["trace[1208577469] 'process raft request' (duration: 128.532978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:36.828225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.154141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:47:36.82865Z","caller":"traceutil/trace.go:171","msg":"trace[136649879] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"237.72105ms","start":"2026-05-29T05:47:36.590901Z","end":"2026-05-29T05:47:36.828623Z","steps":["trace[136649879] 'process raft request' (duration: 237.442865ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:36.82866Z","caller":"traceutil/trace.go:171","msg":"trace[1213927594] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"237.256251ms","start":"2026-05-29T05:47:36.591383Z","end":"2026-05-29T05:47:36.828639Z","steps":["trace[1213927594] 'process raft request' (duration: 237.131588ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:36.82868Z","caller":"traceutil/trace.go:171","msg":"trace[141975183] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"237.016827ms","start":"2026-05-29T05:47:36.591611Z","end":"2026-05-29T05:47:36.828628Z","steps":["trace[141975183] 'process raft request' (duration: 236.953815ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:36.828684Z","caller":"traceutil/trace.go:171","msg":"trace[440879557] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"237.857453ms","start":"2026-05-29T05:47:36.59078Z","end":"2026-05-29T05:47:36.828637Z","steps":["trace[440879557] 'process raft request' (duration: 119.187272ms)","trace[440879557] 'compare' (duration: 118.036248ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:36.87957Z","caller":"traceutil/trace.go:171","msg":"trace[1101326554] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:698; }","duration":"212.143842ms","start":"2026-05-29T05:47:36.667403Z","end":"2026-05-29T05:47:36.879547Z","steps":["trace[1101326554] 'read index received' (duration: 42.64144ms)","trace[1101326554] 'applied index is now lower than readState.Index' (duration: 169.496531ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:36.879623Z","caller":"traceutil/trace.go:171","msg":"trace[730317323] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"257.738559ms","start":"2026-05-29T05:47:36.621865Z","end":"2026-05-29T05:47:36.879603Z","steps":["trace[730317323] 'process raft request' (duration: 257.487394ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:36.879692Z","caller":"traceutil/trace.go:171","msg":"trace[767636339] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"206.05943ms","start":"2026-05-29T05:47:36.673605Z","end":"2026-05-29T05:47:36.879665Z","steps":["trace[767636339] 'process raft request' (duration: 205.874946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:36.879833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.407827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-r7rpz\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-05-29T05:47:36.879889Z","caller":"traceutil/trace.go:171","msg":"trace[1585778948] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-r7rpz; range_end:; response_count:1; response_revision:675; }","duration":"212.526709ms","start":"2026-05-29T05:47:36.667347Z","end":"2026-05-29T05:47:36.879873Z","steps":["trace[1585778948] 'agreement among raft nodes before linearized reading' (duration: 212.329115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:36.8798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.311426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:36.88007Z","caller":"traceutil/trace.go:171","msg":"trace[463294922] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"192.5205ms","start":"2026-05-29T05:47:36.687451Z","end":"2026-05-29T05:47:36.879972Z","steps":["trace[463294922] 'agreement among raft nodes before linearized reading' (duration: 192.273455ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:37.023497Z","caller":"traceutil/trace.go:171","msg":"trace[1432813061] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"137.856382ms","start":"2026-05-29T05:47:36.885622Z","end":"2026-05-29T05:47:37.023478Z","steps":["trace[1432813061] 'process raft request' (duration: 133.792502ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:37.050814Z","caller":"traceutil/trace.go:171","msg":"trace[1200206552] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:704; }","duration":"137.814051ms","start":"2026-05-29T05:47:36.91297Z","end":"2026-05-29T05:47:37.050784Z","steps":["trace[1200206552] 'read index received' (duration: 106.453448ms)","trace[1200206552] 'applied index is now lower than readState.Index' (duration: 31.359243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:37.051039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.035685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:37.051106Z","caller":"traceutil/trace.go:171","msg":"trace[1774932476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"138.154557ms","start":"2026-05-29T05:47:36.912933Z","end":"2026-05-29T05:47:37.051087Z","steps":["trace[1774932476] 'agreement among raft nodes before linearized reading' (duration: 137.970053ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:37.051184Z","caller":"traceutil/trace.go:171","msg":"trace[147081537] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"162.653394ms","start":"2026-05-29T05:47:36.888503Z","end":"2026-05-29T05:47:37.051156Z","steps":["trace[147081537] 'process raft request' (duration: 162.008962ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:37.051195Z","caller":"traceutil/trace.go:171","msg":"trace[1068353660] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"162.307997ms","start":"2026-05-29T05:47:36.888865Z","end":"2026-05-29T05:47:37.051173Z","steps":["trace[1068353660] 'process raft request' (duration: 161.842348ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:38.945131Z","caller":"traceutil/trace.go:171","msg":"trace[1449099124] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"105.541641ms","start":"2026-05-29T05:47:38.839563Z","end":"2026-05-29T05:47:38.945105Z","steps":["trace[1449099124] 'process raft request' (duration: 101.976801ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:38.946622Z","caller":"traceutil/trace.go:171","msg":"trace[906432561] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"106.889488ms","start":"2026-05-29T05:47:38.839713Z","end":"2026-05-29T05:47:38.946602Z","steps":["trace[906432561] 'process raft request' (duration: 106.512581ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:38.946943Z","caller":"traceutil/trace.go:171","msg":"trace[390460038] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"107.157223ms","start":"2026-05-29T05:47:38.839757Z","end":"2026-05-29T05:47:38.946914Z","steps":["trace[390460038] 'process raft request' (duration: 106.550462ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:38.947485Z","caller":"traceutil/trace.go:171","msg":"trace[1434563756] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"106.381308ms","start":"2026-05-29T05:47:38.841091Z","end":"2026-05-29T05:47:38.947473Z","steps":["trace[1434563756] 'process raft request' (duration: 105.471971ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:39.936349Z","caller":"traceutil/trace.go:171","msg":"trace[1104661061] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"213.050434ms","start":"2026-05-29T05:47:39.723276Z","end":"2026-05-29T05:47:39.936326Z","steps":["trace[1104661061] 'process raft request' (duration: 210.561565ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:39.944402Z","caller":"traceutil/trace.go:171","msg":"trace[1438135266] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:742; }","duration":"145.705242ms","start":"2026-05-29T05:47:39.798655Z","end":"2026-05-29T05:47:39.94436Z","steps":["trace[1438135266] 'read index received' (duration: 283.645µs)","trace[1438135266] 'applied index is now lower than readState.Index' (duration: 145.419897ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:39.94576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.055379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:39.945796Z","caller":"traceutil/trace.go:171","msg":"trace[2063337748] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:715; }","duration":"147.157432ms","start":"2026-05-29T05:47:39.798626Z","end":"2026-05-29T05:47:39.945784Z","steps":["trace[2063337748] 'agreement among raft nodes before linearized reading' (duration: 147.040929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:39.945839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.974938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:39.94591Z","caller":"traceutil/trace.go:171","msg":"trace[454505587] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:715; }","duration":"147.124221ms","start":"2026-05-29T05:47:39.798767Z","end":"2026-05-29T05:47:39.945891Z","steps":["trace[454505587] 'agreement among raft nodes before linearized reading' (duration: 146.997298ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:47.258827Z","caller":"traceutil/trace.go:171","msg":"trace[1510066327] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"242.205949ms","start":"2026-05-29T05:47:47.016599Z","end":"2026-05-29T05:47:47.258805Z","steps":["trace[1510066327] 'process raft request' (duration: 242.068557ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:47.268628Z","caller":"traceutil/trace.go:171","msg":"trace[702529175] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:807; }","duration":"245.895002ms","start":"2026-05-29T05:47:47.022708Z","end":"2026-05-29T05:47:47.268603Z","steps":["trace[702529175] 'read index received' (duration: 235.964668ms)","trace[702529175] 'applied index is now lower than readState.Index' (duration: 9.929404ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:47.26874Z","caller":"traceutil/trace.go:171","msg":"trace[888798449] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"249.641495ms","start":"2026-05-29T05:47:47.019075Z","end":"2026-05-29T05:47:47.268717Z","steps":["trace[888798449] 'process raft request' (duration: 249.38747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:47.268899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.112656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-29T05:47:47.268904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.050008ms","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-05-29T05:47:47.268937Z","caller":"traceutil/trace.go:171","msg":"trace[589914064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"246.245389ms","start":"2026-05-29T05:47:47.022681Z","end":"2026-05-29T05:47:47.268926Z","steps":["trace[589914064] 'agreement among raft nodes before linearized reading' (duration: 246.045225ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:47.268961Z","caller":"traceutil/trace.go:171","msg":"trace[1873859458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"240.1408ms","start":"2026-05-29T05:47:47.028803Z","end":"2026-05-29T05:47:47.268944Z","steps":["trace[1873859458] 'agreement among raft nodes before linearized reading' (duration: 239.962327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:50.497963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532707213350850,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:47:50.887505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047543624s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-29T05:47:50.99904Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532707213350850,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:47:51.011854Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.171904415s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:47:51.012311Z","caller":"traceutil/trace.go:171","msg":"trace[1003466017] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"1.197649616s","start":"2026-05-29T05:47:49.814639Z","end":"2026-05-29T05:47:51.012289Z","steps":["trace[1003466017] 'process raft request' (duration: 1.197412351s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:51.012435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:49.814608Z","time spent":"1.197748197s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:47:51.012497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014810935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:47:51.012554Z","caller":"traceutil/trace.go:171","msg":"trace[997885704] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"1.014930547s","start":"2026-05-29T05:47:49.997612Z","end":"2026-05-29T05:47:51.012542Z","steps":["trace[997885704] 'agreement among raft nodes before linearized reading' (duration: 1.014728414s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:51.012284Z","caller":"traceutil/trace.go:171","msg":"trace[968182521] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"1.014590991s","start":"2026-05-29T05:47:49.997666Z","end":"2026-05-29T05:47:51.012257Z","steps":["trace[968182521] 'read index received' (duration: 1.014260825s)","trace[968182521] 'applied index is now lower than readState.Index' (duration: 329.156µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:51.0126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:49.997586Z","time spent":"1.015003758s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:47:51.012785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.211879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:47:51.012831Z","caller":"traceutil/trace.go:171","msg":"trace[903590187] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:793; }","duration":"630.29678ms","start":"2026-05-29T05:47:50.382524Z","end":"2026-05-29T05:47:51.01282Z","steps":["trace[903590187] 'agreement among raft nodes before linearized reading' (duration: 630.221048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:51.012868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:50.382491Z","time spent":"630.368351ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:48:05.213368Z","caller":"traceutil/trace.go:171","msg":"trace[1685021698] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"265.240197ms","start":"2026-05-29T05:48:04.948105Z","end":"2026-05-29T05:48:05.213345Z","steps":["trace[1685021698] 'process raft request' (duration: 222.494158ms)","trace[1685021698] 'compare' (duration: 42.496944ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:48:35.636529Z","caller":"traceutil/trace.go:171","msg":"trace[1298603509] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"120.934149ms","start":"2026-05-29T05:48:35.515571Z","end":"2026-05-29T05:48:35.636506Z","steps":["trace[1298603509] 'process raft request' (duration: 120.373968ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:43.54901Z","caller":"traceutil/trace.go:171","msg":"trace[923227513] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"109.034136ms","start":"2026-05-29T05:48:43.43995Z","end":"2026-05-29T05:48:43.548984Z","steps":["trace[923227513] 'process raft request' (duration: 108.876993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:51.912614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.814701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:48:51.912867Z","caller":"traceutil/trace.go:171","msg":"trace[1497749651] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"188.810131ms","start":"2026-05-29T05:48:51.724041Z","end":"2026-05-29T05:48:51.912851Z","steps":["trace[1497749651] 'process raft request' (duration: 84.686465ms)","trace[1497749651] 'compare' (duration: 103.569425ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:48:52.070843Z","caller":"traceutil/trace.go:171","msg":"trace[711764545] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"151.389511ms","start":"2026-05-29T05:48:51.919428Z","end":"2026-05-29T05:48:52.070817Z","steps":["trace[711764545] 'process raft request' (duration: 147.321068ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:52.070905Z","caller":"traceutil/trace.go:171","msg":"trace[633607846] linearizableReadLoop","detail":"{readStateIndex:1172; appliedIndex:1170; }","duration":"109.647595ms","start":"2026-05-29T05:48:51.961246Z","end":"2026-05-29T05:48:52.070893Z","steps":["trace[633607846] 'read index received' (duration: 105.572672ms)","trace[633607846] 'applied index is now lower than readState.Index' (duration: 4.072063ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:48:52.071014Z","caller":"traceutil/trace.go:171","msg":"trace[2124477727] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"151.525863ms","start":"2026-05-29T05:48:51.919468Z","end":"2026-05-29T05:48:52.070994Z","steps":["trace[2124477727] 'process raft request' (duration: 151.304919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:52.07103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.781497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:48:52.071163Z","caller":"traceutil/trace.go:171","msg":"trace[1781116171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1125; }","duration":"109.93478ms","start":"2026-05-29T05:48:51.96119Z","end":"2026-05-29T05:48:52.071124Z","steps":["trace[1781116171] 'agreement among raft nodes before linearized reading' (duration: 109.745017ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:54.758155Z","caller":"traceutil/trace.go:171","msg":"trace[1558445047] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"173.859632ms","start":"2026-05-29T05:48:54.584266Z","end":"2026-05-29T05:48:54.758125Z","steps":["trace[1558445047] 'process raft request' (duration: 112.138096ms)","trace[1558445047] 'compare' (duration: 61.568173ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:06.277097Z","caller":"traceutil/trace.go:171","msg":"trace[902981230] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"104.985694ms","start":"2026-05-29T05:49:06.172091Z","end":"2026-05-29T05:49:06.277076Z","steps":["trace[902981230] 'process raft request' (duration: 104.572696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:08.159116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.093152ms","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-05-29T05:49:08.15923Z","caller":"traceutil/trace.go:171","msg":"trace[187242933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1176; }","duration":"130.254424ms","start":"2026-05-29T05:49:08.028956Z","end":"2026-05-29T05:49:08.159211Z","steps":["trace[187242933] 'range keys from in-memory index tree' (duration: 129.915849ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:10.08496Z","caller":"traceutil/trace.go:171","msg":"trace[705202649] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"120.426817ms","start":"2026-05-29T05:49:09.964508Z","end":"2026-05-29T05:49:10.084935Z","steps":["trace[705202649] 'process raft request' (duration: 120.273475ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:12.355395Z","caller":"traceutil/trace.go:171","msg":"trace[8789594] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"151.656563ms","start":"2026-05-29T05:49:12.203708Z","end":"2026-05-29T05:49:12.355364Z","steps":["trace[8789594] 'process raft request' (duration: 151.338987ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:12.786284Z","caller":"traceutil/trace.go:171","msg":"trace[704136708] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"151.725024ms","start":"2026-05-29T05:49:12.634525Z","end":"2026-05-29T05:49:12.78625Z","steps":["trace[704136708] 'process raft request' (duration: 151.542621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:16.533854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.484567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:49:16.533944Z","caller":"traceutil/trace.go:171","msg":"trace[1333770755] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1202; }","duration":"281.641139ms","start":"2026-05-29T05:49:16.252278Z","end":"2026-05-29T05:49:16.53392Z","steps":["trace[1333770755] 'count revisions from in-memory index tree' (duration: 281.394725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:16.533907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.894443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T05:49:16.534061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.289826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:49:16.534097Z","caller":"traceutil/trace.go:171","msg":"trace[616375581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"166.156588ms","start":"2026-05-29T05:49:16.367911Z","end":"2026-05-29T05:49:16.534067Z","steps":["trace[616375581] 'range keys from in-memory index tree' (duration: 165.631588ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:16.534144Z","caller":"traceutil/trace.go:171","msg":"trace[1450134564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"121.46674ms","start":"2026-05-29T05:49:16.412657Z","end":"2026-05-29T05:49:16.534124Z","steps":["trace[1450134564] 'range keys from in-memory index tree' (duration: 121.073483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:16.534236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.066016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:49:16.534505Z","caller":"traceutil/trace.go:171","msg":"trace[1348363015] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1202; }","duration":"273.382411ms","start":"2026-05-29T05:49:16.261112Z","end":"2026-05-29T05:49:16.534494Z","steps":["trace[1348363015] 'count revisions from in-memory index tree' (duration: 272.949274ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:22.341686Z","caller":"traceutil/trace.go:171","msg":"trace[1489992556] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"157.960123ms","start":"2026-05-29T05:49:22.183671Z","end":"2026-05-29T05:49:22.341631Z","steps":["trace[1489992556] 'process raft request' (duration: 157.819131ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:45.508284Z","caller":"traceutil/trace.go:171","msg":"trace[1072164053] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"139.738157ms","start":"2026-05-29T05:49:45.368506Z","end":"2026-05-29T05:49:45.508244Z","steps":["trace[1072164053] 'process raft request' (duration: 135.343142ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:46.739536Z","caller":"traceutil/trace.go:171","msg":"trace[1346445347] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"227.315824ms","start":"2026-05-29T05:49:46.512201Z","end":"2026-05-29T05:49:46.739517Z","steps":["trace[1346445347] 'process raft request' (duration: 226.771852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:47.574509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.528398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:49:47.574621Z","caller":"traceutil/trace.go:171","msg":"trace[583119700] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"831.593602ms","start":"2026-05-29T05:49:46.743005Z","end":"2026-05-29T05:49:47.574599Z","steps":["trace[583119700] 'process raft request' (duration: 304.89415ms)","trace[583119700] 'compare' (duration: 526.422646ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:47.57468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:46.742986Z","time spent":"831.667154ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:49:48.510109Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.462185361s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-29T05:49:48.786866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.351632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:49:48.787212Z","caller":"traceutil/trace.go:171","msg":"trace[335072274] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"1.513771779s","start":"2026-05-29T05:49:47.273361Z","end":"2026-05-29T05:49:48.787132Z","steps":["trace[335072274] 'process raft request' (duration: 1.513712288s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:48.787322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:47.273332Z","time spent":"1.513937782s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:48.787326Z","caller":"traceutil/trace.go:171","msg":"trace[833162234] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"2.044005675s","start":"2026-05-29T05:49:46.743287Z","end":"2026-05-29T05:49:48.787292Z","steps":["trace[833162234] 'process raft request' (duration: 1.767158332s)","trace[833162234] 'compare' (duration: 276.196139ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:48.787496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:46.743269Z","time spent":"2.044136888s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:48.787578Z","caller":"traceutil/trace.go:171","msg":"trace[243570459] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"1.82786043s","start":"2026-05-29T05:49:46.959699Z","end":"2026-05-29T05:49:48.787559Z","steps":["trace[243570459] 'process raft request' (duration: 1.827283058s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:48.787724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:46.95967Z","time spent":"1.827961262s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:48.788545Z","caller":"traceutil/trace.go:171","msg":"trace[1370891175] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1400; }","duration":"2.040256844s","start":"2026-05-29T05:49:46.748259Z","end":"2026-05-29T05:49:48.788516Z","steps":["trace[1370891175] 'read index received' (duration: 299.643237ms)","trace[1370891175] 'applied index is now lower than readState.Index' (duration: 1.740611347s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:48.789094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.040837857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T05:49:48.789131Z","caller":"traceutil/trace.go:171","msg":"trace[504377197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1344; }","duration":"2.040894058s","start":"2026-05-29T05:49:46.748228Z","end":"2026-05-29T05:49:48.789122Z","steps":["trace[504377197] 'agreement among raft nodes before linearized reading' (duration: 2.040772416s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:48.789155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:46.748221Z","time spent":"2.040929209s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:49:48.789343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.874759098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:49:48.789395Z","caller":"traceutil/trace.go:171","msg":"trace[232736249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"1.87483665s","start":"2026-05-29T05:49:46.914546Z","end":"2026-05-29T05:49:48.789383Z","steps":["trace[232736249] 'agreement among raft nodes before linearized reading' (duration: 1.874756978s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:48.789439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:46.914534Z","time spent":"1.874892991s","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:49:48.789348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.958224592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:49:48.789585Z","caller":"traceutil/trace.go:171","msg":"trace[1817252095] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1344; }","duration":"1.958457237s","start":"2026-05-29T05:49:46.831111Z","end":"2026-05-29T05:49:48.789568Z","steps":["trace[1817252095] 'agreement among raft nodes before linearized reading' (duration: 1.958202271s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:48.789648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:46.831095Z","time spent":"1.958541038s","remote":"127.0.0.1:41584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-05-29T05:49:48.789932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.009041703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:49:48.790004Z","caller":"traceutil/trace.go:171","msg":"trace[904390901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"2.009126925s","start":"2026-05-29T05:49:46.780865Z","end":"2026-05-29T05:49:48.789992Z","steps":["trace[904390901] 'agreement among raft nodes before linearized reading' (duration: 2.008218905s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:48.790109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:46.780858Z","time spent":"2.009239267s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:49:49.529562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.544348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:49:49.52983Z","caller":"traceutil/trace.go:171","msg":"trace[239905802] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"736.708603ms","start":"2026-05-29T05:49:48.793099Z","end":"2026-05-29T05:49:49.529807Z","steps":["trace[239905802] 'process raft request' (duration: 338.835598ms)","trace[239905802] 'compare' (duration: 397.15935ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:49.529954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:48.793078Z","time spent":"736.833145ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:49.530142Z","caller":"traceutil/trace.go:171","msg":"trace[1413952079] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1404; }","duration":"736.085259ms","start":"2026-05-29T05:49:48.794041Z","end":"2026-05-29T05:49:49.530126Z","steps":["trace[1413952079] 'read index received' (duration: 337.841896ms)","trace[1413952079] 'applied index is now lower than readState.Index' (duration: 398.242163ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:49.530309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.265753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:49:49.530359Z","caller":"traceutil/trace.go:171","msg":"trace[46667944] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1347; }","duration":"736.375395ms","start":"2026-05-29T05:49:48.793971Z","end":"2026-05-29T05:49:49.530346Z","steps":["trace[46667944] 'agreement among raft nodes before linearized reading' (duration: 736.291693ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:49.530324Z","caller":"traceutil/trace.go:171","msg":"trace[1831100842] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"736.959147ms","start":"2026-05-29T05:49:48.79335Z","end":"2026-05-29T05:49:49.530309Z","steps":["trace[1831100842] 'process raft request' (duration: 736.309774ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:49.530429Z","caller":"traceutil/trace.go:171","msg":"trace[708179446] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"736.280813ms","start":"2026-05-29T05:49:48.794139Z","end":"2026-05-29T05:49:49.53042Z","steps":["trace[708179446] 'process raft request' (duration: 735.813133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:49.530491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:48.794129Z","time spent":"736.325403ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:49:49.530507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:48.793331Z","time spent":"737.109951ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:49:49.530397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:48.793934Z","time spent":"736.455567ms","remote":"127.0.0.1:41584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-05-29T05:49:49.530755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.224702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:49:49.530843Z","caller":"traceutil/trace.go:171","msg":"trace[1509061440] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1347; }","duration":"398.365526ms","start":"2026-05-29T05:49:49.132451Z","end":"2026-05-29T05:49:49.530817Z","steps":["trace[1509061440] 'agreement among raft nodes before linearized reading' (duration: 398.166091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:49.530896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:49.132432Z","time spent":"398.454179ms","remote":"127.0.0.1:41732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:49:49.530907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.853757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:49:49.530947Z","caller":"traceutil/trace.go:171","msg":"trace[1335160298] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1347; }","duration":"148.934579ms","start":"2026-05-29T05:49:49.382001Z","end":"2026-05-29T05:49:49.530936Z","steps":["trace[1335160298] 'agreement among raft nodes before linearized reading' (duration: 148.866457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:49.530676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.733667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:49:49.531003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.149925ms","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-05-29T05:49:49.53107Z","caller":"traceutil/trace.go:171","msg":"trace[1427460595] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1347; }","duration":"697.229487ms","start":"2026-05-29T05:49:48.833824Z","end":"2026-05-29T05:49:49.531053Z","steps":["trace[1427460595] 'agreement among raft nodes before linearized reading' (duration: 697.114825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:49.531119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:48.833784Z","time spent":"697.320579ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:49:49.531022Z","caller":"traceutil/trace.go:171","msg":"trace[1084834618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"733.111576ms","start":"2026-05-29T05:49:48.79789Z","end":"2026-05-29T05:49:49.531001Z","steps":["trace[1084834618] 'agreement among raft nodes before linearized reading' (duration: 732.463871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:49.531788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:48.797878Z","time spent":"733.494704ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:49:51.914082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000644182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-29T05:49:51.914183Z","caller":"traceutil/trace.go:171","msg":"trace[1213664677] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000801456s","start":"2026-05-29T05:49:49.913359Z","end":"2026-05-29T05:49:51.91416Z","steps":["trace[1213664677] 'agreement among raft nodes before linearized reading' (duration: 2.000668393s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:51.914301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:49.913319Z","time spent":"2.000911619s","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:49:52.492188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.838635723s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-29T05:49:52.492363Z","caller":"traceutil/trace.go:171","msg":"trace[584286194] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1407; }","duration":"2.913789921s","start":"2026-05-29T05:49:49.578554Z","end":"2026-05-29T05:49:52.492344Z","steps":["trace[584286194] 'read index received' (duration: 74.907302ms)","trace[584286194] 'applied index is now lower than readState.Index' (duration: 2.838881069s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:52.493915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.823052ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:49:52.494901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.916325626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:49:52.494962Z","caller":"traceutil/trace.go:171","msg":"trace[58506033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1347; }","duration":"2.916435498s","start":"2026-05-29T05:49:49.578511Z","end":"2026-05-29T05:49:52.494946Z","steps":["trace[58506033] 'agreement among raft nodes before linearized reading' (duration: 2.916235054s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:52.495176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:49.578489Z","time spent":"2.91650301s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:49:52.494037Z","caller":"traceutil/trace.go:171","msg":"trace[989765680] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1347; }","duration":"337.956285ms","start":"2026-05-29T05:49:52.156062Z","end":"2026-05-29T05:49:52.494018Z","steps":["trace[989765680] 'range keys from in-memory index tree' (duration: 337.805472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:52.525553Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.73265337s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:49:52.525959Z","caller":"traceutil/trace.go:171","msg":"trace[2140421490] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"1.73316052s","start":"2026-05-29T05:49:50.792777Z","end":"2026-05-29T05:49:52.525937Z","steps":["trace[2140421490] 'process raft request' (duration: 1.733019467s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:52.526145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:50.792748Z","time spent":"1.733319884s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:49:52.526835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.698565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T05:49:52.526927Z","caller":"traceutil/trace.go:171","msg":"trace[980439751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1348; }","duration":"990.826758ms","start":"2026-05-29T05:49:51.536085Z","end":"2026-05-29T05:49:52.526912Z","steps":["trace[980439751] 'agreement among raft nodes before linearized reading' (duration: 990.655974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:52.526992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:51.536058Z","time spent":"990.923619ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:49:52.527222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.54547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:49:52.527304Z","caller":"traceutil/trace.go:171","msg":"trace[382292355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1348; }","duration":"611.664453ms","start":"2026-05-29T05:49:51.915628Z","end":"2026-05-29T05:49:52.527292Z","steps":["trace[382292355] 'agreement among raft nodes before linearized reading' (duration: 611.561961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:52.527362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:51.915603Z","time spent":"611.748265ms","remote":"127.0.0.1:41424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:49:52.527367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.280617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:49:52.527458Z","caller":"traceutil/trace.go:171","msg":"trace[1436679655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1348; }","duration":"991.42008ms","start":"2026-05-29T05:49:51.536018Z","end":"2026-05-29T05:49:52.527438Z","steps":["trace[1436679655] 'agreement among raft nodes before linearized reading' (duration: 991.190255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:52.527521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:51.535996Z","time spent":"991.509602ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:49:52.52753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.790107378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:49:52.527616Z","caller":"traceutil/trace.go:171","msg":"trace[397415281] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1348; }","duration":"1.790250791s","start":"2026-05-29T05:49:50.737346Z","end":"2026-05-29T05:49:52.527597Z","steps":["trace[397415281] 'agreement among raft nodes before linearized reading' (duration: 1.790079778s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:52.527748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.731557759s","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-05-29T05:49:52.527667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:50.737327Z","time spent":"1.790330142s","remote":"127.0.0.1:41752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:49:52.527821Z","caller":"traceutil/trace.go:171","msg":"trace[1245338306] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1348; }","duration":"2.731678951s","start":"2026-05-29T05:49:49.796122Z","end":"2026-05-29T05:49:52.527801Z","steps":["trace[1245338306] 'agreement among raft nodes before linearized reading' (duration: 2.730570948s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:52.527884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:49.796101Z","time spent":"2.731764043s","remote":"127.0.0.1:41648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:49:52.52811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.735748275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T05:49:52.528198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.319517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:49:52.528291Z","caller":"traceutil/trace.go:171","msg":"trace[1520368586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1348; }","duration":"991.983812ms","start":"2026-05-29T05:49:51.536269Z","end":"2026-05-29T05:49:52.528252Z","steps":["trace[1520368586] 'agreement among raft nodes before linearized reading' (duration: 991.077983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:52.528359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:51.536261Z","time spent":"992.081775ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T05:49:52.528166Z","caller":"traceutil/trace.go:171","msg":"trace[1123340580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1348; }","duration":"1.736160524s","start":"2026-05-29T05:49:50.791991Z","end":"2026-05-29T05:49:52.528152Z","steps":["trace[1123340580] 'agreement among raft nodes before linearized reading' (duration: 1.734796295s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:52.528847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:50.79197Z","time spent":"1.73686112s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T05:49:52.76132Z","caller":"traceutil/trace.go:171","msg":"trace[1525565316] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"202.392105ms","start":"2026-05-29T05:49:52.55889Z","end":"2026-05-29T05:49:52.761282Z","steps":["trace[1525565316] 'process raft request' (duration: 173.724903ms)","trace[1525565316] 'compare' (duration: 28.520269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:50:00.931004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.047697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:50:00.931201Z","caller":"traceutil/trace.go:171","msg":"trace[1227554322] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"165.684467ms","start":"2026-05-29T05:50:00.765495Z","end":"2026-05-29T05:50:00.931179Z","steps":["trace[1227554322] 'process raft request' (duration: 24.345484ms)","trace[1227554322] 'compare' (duration: 140.904824ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:50:00.933676Z","caller":"traceutil/trace.go:171","msg":"trace[256807036] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"167.964576ms","start":"2026-05-29T05:50:00.765693Z","end":"2026-05-29T05:50:00.933657Z","steps":["trace[256807036] 'process raft request' (duration: 167.869104ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:50:00.933787Z","caller":"traceutil/trace.go:171","msg":"trace[179328511] linearizableReadLoop","detail":"{readStateIndex:1449; appliedIndex:1445; }","duration":"151.231682ms","start":"2026-05-29T05:50:00.782537Z","end":"2026-05-29T05:50:00.933768Z","steps":["trace[179328511] 'read index received' (duration: 7.332255ms)","trace[179328511] 'applied index is now lower than readState.Index' (duration: 143.897607ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:50:00.933843Z","caller":"traceutil/trace.go:171","msg":"trace[1929043784] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"166.581876ms","start":"2026-05-29T05:50:00.767224Z","end":"2026-05-29T05:50:00.933806Z","steps":["trace[1929043784] 'process raft request' (duration: 166.390842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:50:00.933892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.351874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:1 size:679"} {"level":"warn","ts":"2026-05-29T05:50:00.933954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.025369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-05-29T05:50:00.933965Z","caller":"traceutil/trace.go:171","msg":"trace[2096865962] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:1; response_revision:1385; }","duration":"151.445667ms","start":"2026-05-29T05:50:00.782501Z","end":"2026-05-29T05:50:00.933946Z","steps":["trace[2096865962] 'agreement among raft nodes before linearized reading' (duration: 151.334425ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:50:00.934006Z","caller":"traceutil/trace.go:171","msg":"trace[1829658694] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver; range_end:; response_count:1; response_revision:1385; }","duration":"142.09456ms","start":"2026-05-29T05:50:00.791895Z","end":"2026-05-29T05:50:00.933989Z","steps":["trace[1829658694] 'agreement among raft nodes before linearized reading' (duration: 141.992758ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:50:00.933702Z","caller":"traceutil/trace.go:171","msg":"trace[2088016099] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"168.118559ms","start":"2026-05-29T05:50:00.765563Z","end":"2026-05-29T05:50:00.933682Z","steps":["trace[2088016099] 'process raft request' (duration: 167.926795ms)"],"step_count":1}