{"level":"warn","ts":"2026-05-19T17:50:28.208747Z","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-19T17:50:28.208829Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.20:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.20:2380","--initial-cluster=instance=https://199.19.213.20:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.20:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.20: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-19T17:50:28.208888Z","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-19T17:50:28.2089Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.20:2380"]} {"level":"info","ts":"2026-05-19T17:50:28.208915Z","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-19T17:50:28.20953Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.20:2379"]} {"level":"info","ts":"2026-05-19T17:50:28.209606Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.20:2380"],"listen-peer-urls":["https://199.19.213.20:2380"],"advertise-client-urls":["https://199.19.213.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.20: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-19T17:50:28.258533Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"48.729399ms"} {"level":"info","ts":"2026-05-19T17:50:28.416999Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"185293d8d40ffce0","cluster-id":"cc857afa6e1fa648"} {"level":"info","ts":"2026-05-19T17:50:28.417269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T17:50:28.41733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became follower at term 0"} {"level":"info","ts":"2026-05-19T17:50:28.417354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 185293d8d40ffce0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T17:50:28.417393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became follower at term 1"} {"level":"info","ts":"2026-05-19T17:50:28.417485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 switched to configuration voters=(1752625764480580832)"} {"level":"warn","ts":"2026-05-19T17:50:28.554033Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T17:50:28.641122Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T17:50:28.687421Z","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-19T17:50:28.789648Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"185293d8d40ffce0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T17:50:28.790011Z","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-19T17:50:28.79019Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"185293d8d40ffce0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T17:50:28.790394Z","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-19T17:50:28.792932Z","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-19T17:50:28.796957Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.20:2380"} {"level":"info","ts":"2026-05-19T17:50:28.797023Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.20:2380"} {"level":"info","ts":"2026-05-19T17:50:28.796908Z","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-19T17:50:28.79821Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T17:50:28.798154Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"185293d8d40ffce0","initial-advertise-peer-urls":["https://199.19.213.20:2380"],"listen-peer-urls":["https://199.19.213.20:2380"],"advertise-client-urls":["https://199.19.213.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T17:50:28.798226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 switched to configuration voters=(1752625764480580832)"} {"level":"info","ts":"2026-05-19T17:50:28.798448Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cc857afa6e1fa648","local-member-id":"185293d8d40ffce0","added-peer-id":"185293d8d40ffce0","added-peer-peer-urls":["https://199.19.213.20:2380"]} {"level":"info","ts":"2026-05-19T17:50:29.418933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T17:50:29.419013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T17:50:29.419058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 received MsgPreVoteResp from 185293d8d40ffce0 at term 1"} {"level":"info","ts":"2026-05-19T17:50:29.419078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became candidate at term 2"} {"level":"info","ts":"2026-05-19T17:50:29.41909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 received MsgVoteResp from 185293d8d40ffce0 at term 2"} {"level":"info","ts":"2026-05-19T17:50:29.419117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became leader at term 2"} {"level":"info","ts":"2026-05-19T17:50:29.419138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 185293d8d40ffce0 elected leader 185293d8d40ffce0 at term 2"} {"level":"info","ts":"2026-05-19T17:50:29.459388Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"185293d8d40ffce0","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.20:2379]}","request-path":"/0/members/185293d8d40ffce0/attributes","cluster-id":"cc857afa6e1fa648","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T17:50:29.459441Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T17:50:29.459411Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T17:50:29.459532Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:50:29.460009Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T17:50:29.460047Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T17:50:29.461171Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.20:2379"} {"level":"info","ts":"2026-05-19T17:50:29.462961Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T17:50:29.507079Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cc857afa6e1fa648","local-member-id":"185293d8d40ffce0","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:50:29.507322Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:50:29.507695Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:50:31.423364Z","caller":"traceutil/trace.go:171","msg":"trace[1638057211] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"245.226374ms","start":"2026-05-19T17:50:31.178052Z","end":"2026-05-19T17:50:31.423279Z","steps":["trace[1638057211] 'process raft request' (duration: 233.649779ms)","trace[1638057211] 'compare' (duration: 11.170765ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:31.423441Z","caller":"traceutil/trace.go:171","msg":"trace[60013894] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"166.50504ms","start":"2026-05-19T17:50:31.256887Z","end":"2026-05-19T17:50:31.423392Z","steps":["trace[60013894] 'process raft request' (duration: 166.250874ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.423552Z","caller":"traceutil/trace.go:171","msg":"trace[1001382222] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"166.489921ms","start":"2026-05-19T17:50:31.256964Z","end":"2026-05-19T17:50:31.423454Z","steps":["trace[1001382222] 'process raft request' (duration: 166.259795ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.423611Z","caller":"traceutil/trace.go:171","msg":"trace[1911510374] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"166.359007ms","start":"2026-05-19T17:50:31.257216Z","end":"2026-05-19T17:50:31.423575Z","steps":["trace[1911510374] 'process raft request' (duration: 166.07485ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.42356Z","caller":"traceutil/trace.go:171","msg":"trace[1708641094] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"166.293625ms","start":"2026-05-19T17:50:31.25718Z","end":"2026-05-19T17:50:31.423474Z","steps":["trace[1708641094] 'process raft request' (duration: 166.07652ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.423713Z","caller":"traceutil/trace.go:171","msg":"trace[480046675] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"166.90969ms","start":"2026-05-19T17:50:31.256737Z","end":"2026-05-19T17:50:31.423647Z","steps":["trace[480046675] 'process raft request' (duration: 166.351727ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.423395Z","caller":"traceutil/trace.go:171","msg":"trace[893002823] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"244.677371ms","start":"2026-05-19T17:50:31.178679Z","end":"2026-05-19T17:50:31.423356Z","steps":["trace[893002823] 'read index received' (duration: 85.626094ms)","trace[893002823] 'applied index is now lower than readState.Index' (duration: 159.049907ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:31.425651Z","caller":"traceutil/trace.go:171","msg":"trace[880853846] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"166.688915ms","start":"2026-05-19T17:50:31.256938Z","end":"2026-05-19T17:50:31.423626Z","steps":["trace[880853846] 'process raft request' (duration: 166.245994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.427059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.32249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:50:31.427162Z","caller":"traceutil/trace.go:171","msg":"trace[703368593] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:9; }","duration":"248.469224ms","start":"2026-05-19T17:50:31.178673Z","end":"2026-05-19T17:50:31.427143Z","steps":["trace[703368593] 'agreement among raft nodes before linearized reading' (duration: 247.717916ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.495108Z","caller":"traceutil/trace.go:171","msg":"trace[810544198] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"228.860201ms","start":"2026-05-19T17:50:31.266207Z","end":"2026-05-19T17:50:31.495067Z","steps":["trace[810544198] 'process raft request' (duration: 228.616805ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.495187Z","caller":"traceutil/trace.go:171","msg":"trace[1226235228] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"221.154603ms","start":"2026-05-19T17:50:31.273988Z","end":"2026-05-19T17:50:31.495143Z","steps":["trace[1226235228] 'process raft request' (duration: 221.017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.495363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.271377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T17:50:31.495354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.084446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T17:50:31.495369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.324837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.20\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:50:31.495419Z","caller":"traceutil/trace.go:171","msg":"trace[1378980385] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"239.365239ms","start":"2026-05-19T17:50:31.256037Z","end":"2026-05-19T17:50:31.495402Z","steps":["trace[1378980385] 'agreement among raft nodes before linearized reading' (duration: 239.260677ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.495442Z","caller":"traceutil/trace.go:171","msg":"trace[1614669634] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"229.21796ms","start":"2026-05-19T17:50:31.266198Z","end":"2026-05-19T17:50:31.495416Z","steps":["trace[1614669634] 'agreement among raft nodes before linearized reading' (duration: 228.912102ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.495463Z","caller":"traceutil/trace.go:171","msg":"trace[423666823] range","detail":"{range_begin:/registry/masterleases/199.19.213.20; range_end:; response_count:0; response_revision:11; }","duration":"241.44364ms","start":"2026-05-19T17:50:31.253996Z","end":"2026-05-19T17:50:31.49544Z","steps":["trace[423666823] 'agreement among raft nodes before linearized reading' (duration: 241.235925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.495709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.908975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:50:31.495756Z","caller":"traceutil/trace.go:171","msg":"trace[1259832265] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"187.979928ms","start":"2026-05-19T17:50:31.307765Z","end":"2026-05-19T17:50:31.495745Z","steps":["trace[1259832265] 'agreement among raft nodes before linearized reading' (duration: 187.911106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.495836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.016824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:50:31.495898Z","caller":"traceutil/trace.go:171","msg":"trace[146279516] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"268.087846ms","start":"2026-05-19T17:50:31.227783Z","end":"2026-05-19T17:50:31.495871Z","steps":["trace[146279516] 'agreement among raft nodes before linearized reading' (duration: 268.023303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.862077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.385741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-19T17:50:31.862534Z","caller":"traceutil/trace.go:171","msg":"trace[1410540295] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"430.789911ms","start":"2026-05-19T17:50:31.431673Z","end":"2026-05-19T17:50:31.862463Z","steps":["trace[1410540295] 'process raft request' (duration: 430.585716ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.862658Z","caller":"traceutil/trace.go:171","msg":"trace[1603220467] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"430.565536ms","start":"2026-05-19T17:50:31.43194Z","end":"2026-05-19T17:50:31.862506Z","steps":["trace[1603220467] 'process raft request' (duration: 430.414012ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.862714Z","caller":"traceutil/trace.go:171","msg":"trace[131321590] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"430.658838ms","start":"2026-05-19T17:50:31.431948Z","end":"2026-05-19T17:50:31.862606Z","steps":["trace[131321590] 'process raft request' (duration: 430.439153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.862738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.431636Z","time spent":"430.998826ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:31.862851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.431929Z","time spent":"430.819952ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:31.862922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.431925Z","time spent":"430.882623ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:50:31.862995Z","caller":"traceutil/trace.go:171","msg":"trace[511133355] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"430.923935ms","start":"2026-05-19T17:50:31.432062Z","end":"2026-05-19T17:50:31.862986Z","steps":["trace[511133355] 'process raft request' (duration: 430.407692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.863118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.731906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-05-19T17:50:31.863153Z","caller":"traceutil/trace.go:171","msg":"trace[1984928448] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"429.778937ms","start":"2026-05-19T17:50:31.433366Z","end":"2026-05-19T17:50:31.863145Z","steps":["trace[1984928448] 'agreement among raft nodes before linearized reading' (duration: 429.655704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.86315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.43204Z","time spent":"430.988336ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:31.863175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.433167Z","time spent":"430.004543ms","remote":"127.0.0.1:36418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3513,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-19T17:50:31.863507Z","caller":"traceutil/trace.go:171","msg":"trace[1328577109] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"365.716574ms","start":"2026-05-19T17:50:31.497777Z","end":"2026-05-19T17:50:31.863493Z","steps":["trace[1328577109] 'process raft request' (duration: 364.959294ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.863363Z","caller":"traceutil/trace.go:171","msg":"trace[393086869] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"430.28215ms","start":"2026-05-19T17:50:31.433052Z","end":"2026-05-19T17:50:31.863334Z","steps":["trace[393086869] 'process raft request' (duration: 429.626013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.863626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.433021Z","time spent":"430.574447ms","remote":"127.0.0.1:36720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:31.863605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.497746Z","time spent":"365.828337ms","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:50:31.863721Z","caller":"traceutil/trace.go:171","msg":"trace[2121310490] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"363.043578ms","start":"2026-05-19T17:50:31.500626Z","end":"2026-05-19T17:50:31.863669Z","steps":["trace[2121310490] 'process raft request' (duration: 362.142105ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.862923Z","caller":"traceutil/trace.go:171","msg":"trace[89305702] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"356.033886ms","start":"2026-05-19T17:50:31.506867Z","end":"2026-05-19T17:50:31.862901Z","steps":["trace[89305702] 'process raft request' (duration: 355.953443ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:31.863Z","caller":"traceutil/trace.go:171","msg":"trace[5659578] linearizableReadLoop","detail":"{readStateIndex:20; appliedIndex:14; }","duration":"367.931878ms","start":"2026-05-19T17:50:31.495059Z","end":"2026-05-19T17:50:31.862991Z","steps":["trace[5659578] 'read index received' (duration: 199.167823ms)","trace[5659578] 'applied index is now lower than readState.Index' (duration: 168.762395ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:31.863877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.500611Z","time spent":"363.191782ms","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:31.864099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.407511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:50:31.864193Z","caller":"traceutil/trace.go:171","msg":"trace[1409134499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19; }","duration":"366.533713ms","start":"2026-05-19T17:50:31.497649Z","end":"2026-05-19T17:50:31.864182Z","steps":["trace[1409134499] 'agreement among raft nodes before linearized reading' (duration: 366.425751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.864233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.506849Z","time spent":"357.044151ms","remote":"127.0.0.1:36418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:31.864267Z","caller":"traceutil/trace.go:171","msg":"trace[2029201177] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"433.829786ms","start":"2026-05-19T17:50:31.430397Z","end":"2026-05-19T17:50:31.864227Z","steps":["trace[2029201177] 'process raft request' (duration: 263.756229ms)","trace[2029201177] 'compare' (duration: 167.243408ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:31.864311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.403581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-19T17:50:31.864368Z","caller":"traceutil/trace.go:171","msg":"trace[608349173] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"307.504224ms","start":"2026-05-19T17:50:31.556851Z","end":"2026-05-19T17:50:31.864355Z","steps":["trace[608349173] 'agreement among raft nodes before linearized reading' (duration: 307.380651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.864408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.556834Z","time spent":"307.566686ms","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-19T17:50:31.864406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.430376Z","time spent":"433.94232ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:31.864305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.390312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:50:31.864497Z","caller":"traceutil/trace.go:171","msg":"trace[294925589] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:19; }","duration":"163.648059ms","start":"2026-05-19T17:50:31.70082Z","end":"2026-05-19T17:50:31.864468Z","steps":["trace[294925589] 'agreement among raft nodes before linearized reading' (duration: 163.380723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.864621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.076238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-c6psx\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-05-19T17:50:31.864675Z","caller":"traceutil/trace.go:171","msg":"trace[2054584847] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-c6psx; range_end:; response_count:1; response_revision:19; }","duration":"365.151169ms","start":"2026-05-19T17:50:31.499511Z","end":"2026-05-19T17:50:31.864662Z","steps":["trace[2054584847] 'agreement among raft nodes before linearized reading' (duration: 365.031736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:31.864716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.499499Z","time spent":"365.209001ms","remote":"127.0.0.1:36522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-c6psx\" "} {"level":"warn","ts":"2026-05-19T17:50:31.864653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.497632Z","time spent":"367.008845ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:50:32.226771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.254238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-19T17:50:32.226998Z","caller":"traceutil/trace.go:171","msg":"trace[289785331] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"322.123342ms","start":"2026-05-19T17:50:31.904838Z","end":"2026-05-19T17:50:32.226961Z","steps":["trace[289785331] 'process raft request' (duration: 322.05442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:32.227074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.90482Z","time spent":"322.225206ms","remote":"127.0.0.1:36346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:50:32.227108Z","caller":"traceutil/trace.go:171","msg":"trace[1964540041] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"322.476751ms","start":"2026-05-19T17:50:31.904559Z","end":"2026-05-19T17:50:32.227036Z","steps":["trace[1964540041] 'process raft request' (duration: 110.875714ms)","trace[1964540041] 'compare' (duration: 211.103644ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:32.227375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.904532Z","time spent":"322.702537ms","remote":"127.0.0.1:36720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:50:32.243401Z","caller":"traceutil/trace.go:171","msg":"trace[1291499929] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"329.991485ms","start":"2026-05-19T17:50:31.913372Z","end":"2026-05-19T17:50:32.243363Z","steps":["trace[1291499929] 'process raft request' (duration: 329.715298ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:32.243575Z","caller":"traceutil/trace.go:171","msg":"trace[371620729] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"328.860337ms","start":"2026-05-19T17:50:31.914687Z","end":"2026-05-19T17:50:32.243547Z","steps":["trace[371620729] 'process raft request' (duration: 328.58904ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:32.243648Z","caller":"traceutil/trace.go:171","msg":"trace[244954255] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"327.304399ms","start":"2026-05-19T17:50:31.916316Z","end":"2026-05-19T17:50:32.24362Z","steps":["trace[244954255] 'process raft request' (duration: 327.067383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:32.243632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.913358Z","time spent":"330.142388ms","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:32.243724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.916306Z","time spent":"327.38301ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:32.243751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.914678Z","time spent":"328.99284ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:50:32.243872Z","caller":"traceutil/trace.go:171","msg":"trace[1602113552] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"328.12476ms","start":"2026-05-19T17:50:31.915732Z","end":"2026-05-19T17:50:32.243857Z","steps":["trace[1602113552] 'process raft request' (duration: 327.588877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:32.243939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.915717Z","time spent":"328.195862ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:50:32.244003Z","caller":"traceutil/trace.go:171","msg":"trace[327548580] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"327.010933ms","start":"2026-05-19T17:50:31.916982Z","end":"2026-05-19T17:50:32.243993Z","steps":["trace[327548580] 'process raft request' (duration: 326.486049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:32.244059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.91697Z","time spent":"327.066234ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:50:32.244066Z","caller":"traceutil/trace.go:171","msg":"trace[121936836] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"328.12704ms","start":"2026-05-19T17:50:31.915928Z","end":"2026-05-19T17:50:32.244055Z","steps":["trace[121936836] 'process raft request' (duration: 327.423962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:32.244372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:31.915921Z","time spent":"328.357856ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:32.258044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.046841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:50:32.258088Z","caller":"traceutil/trace.go:171","msg":"trace[189490562] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:37; }","duration":"103.155974ms","start":"2026-05-19T17:50:32.154923Z","end":"2026-05-19T17:50:32.258079Z","steps":["trace[189490562] 'agreement among raft nodes before linearized reading' (duration: 103.042141ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:32.408757Z","caller":"traceutil/trace.go:171","msg":"trace[566745424] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"147.783439ms","start":"2026-05-19T17:50:32.260941Z","end":"2026-05-19T17:50:32.408725Z","steps":["trace[566745424] 'process raft request' (duration: 77.608425ms)","trace[566745424] 'compare' (duration: 69.945249ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:32.408985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.761834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:50:32.409102Z","caller":"traceutil/trace.go:171","msg":"trace[981751543] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:40; }","duration":"148.939859ms","start":"2026-05-19T17:50:32.260137Z","end":"2026-05-19T17:50:32.409077Z","steps":["trace[981751543] 'agreement among raft nodes before linearized reading' (duration: 148.630661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:32.910251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.954345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-19T17:50:32.910499Z","caller":"traceutil/trace.go:171","msg":"trace[1942319611] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"321.031104ms","start":"2026-05-19T17:50:32.589439Z","end":"2026-05-19T17:50:32.91047Z","steps":["trace[1942319611] 'process raft request' (duration: 320.984213ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:32.910528Z","caller":"traceutil/trace.go:171","msg":"trace[761030375] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"329.777809ms","start":"2026-05-19T17:50:32.580698Z","end":"2026-05-19T17:50:32.910476Z","steps":["trace[761030375] 'process raft request' (duration: 139.490466ms)","trace[761030375] 'compare' (duration: 189.801712ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:32.910668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:32.589417Z","time spent":"321.20689ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:50:32.910558Z","caller":"traceutil/trace.go:171","msg":"trace[207960966] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"328.418116ms","start":"2026-05-19T17:50:32.582104Z","end":"2026-05-19T17:50:32.910522Z","steps":["trace[207960966] 'process raft request' (duration: 328.253392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:32.910795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:32.580679Z","time spent":"329.970514ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1216,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:50:32.910807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:32.582083Z","time spent":"328.670973ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:50:33.052513Z","caller":"traceutil/trace.go:171","msg":"trace[1079639487] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"112.645726ms","start":"2026-05-19T17:50:32.939827Z","end":"2026-05-19T17:50:33.052473Z","steps":["trace[1079639487] 'process raft request' (duration: 112.417911ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:33.052573Z","caller":"traceutil/trace.go:171","msg":"trace[2094475377] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"113.510688ms","start":"2026-05-19T17:50:32.939011Z","end":"2026-05-19T17:50:33.052522Z","steps":["trace[2094475377] 'process raft request' (duration: 85.598002ms)","trace[2094475377] 'compare' (duration: 27.423614ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:33.478195Z","caller":"traceutil/trace.go:171","msg":"trace[542800462] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"119.94625ms","start":"2026-05-19T17:50:33.358191Z","end":"2026-05-19T17:50:33.478137Z","steps":["trace[542800462] 'process raft request' (duration: 64.936444ms)","trace[542800462] 'compare' (duration: 54.732659ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:35.07985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.977715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:50:35.080266Z","caller":"traceutil/trace.go:171","msg":"trace[1932731187] transaction","detail":"{read_only:false; response_revision:192; number_of_response:1; }","duration":"168.18834ms","start":"2026-05-19T17:50:34.912053Z","end":"2026-05-19T17:50:35.080241Z","steps":["trace[1932731187] 'process raft request' (duration: 168.089238ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:35.080614Z","caller":"traceutil/trace.go:171","msg":"trace[242874605] transaction","detail":"{read_only:false; response_revision:191; number_of_response:1; }","duration":"171.246472ms","start":"2026-05-19T17:50:34.909352Z","end":"2026-05-19T17:50:35.080598Z","steps":["trace[242874605] 'process raft request' (duration: 47.38776ms)","trace[242874605] 'compare' (duration: 122.654188ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:35.333699Z","caller":"traceutil/trace.go:171","msg":"trace[609626111] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"244.528415ms","start":"2026-05-19T17:50:35.089127Z","end":"2026-05-19T17:50:35.333656Z","steps":["trace[609626111] 'process raft request' (duration: 243.75839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:35.746922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.430046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:50:35.747044Z","caller":"traceutil/trace.go:171","msg":"trace[1919103054] linearizableReadLoop","detail":"{readStateIndex:199; appliedIndex:197; }","duration":"473.684358ms","start":"2026-05-19T17:50:35.273342Z","end":"2026-05-19T17:50:35.747026Z","steps":["trace[1919103054] 'read index received' (duration: 59.558969ms)","trace[1919103054] 'applied index is now lower than readState.Index' (duration: 414.123929ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:35.747294Z","caller":"traceutil/trace.go:171","msg":"trace[352686366] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"657.019661ms","start":"2026-05-19T17:50:35.090253Z","end":"2026-05-19T17:50:35.747273Z","steps":["trace[352686366] 'process raft request' (duration: 367.152316ms)","trace[352686366] 'compare' (duration: 289.186761ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:35.747383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:35.090237Z","time spent":"657.107322ms","remote":"127.0.0.1:36644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:35.74743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.390723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T17:50:35.747525Z","caller":"traceutil/trace.go:171","msg":"trace[1351973926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:194; }","duration":"182.528466ms","start":"2026-05-19T17:50:35.564967Z","end":"2026-05-19T17:50:35.747496Z","steps":["trace[1351973926] 'agreement among raft nodes before linearized reading' (duration: 182.364103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:35.747541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.374403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18b108d1a2475795\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-05-19T17:50:35.747644Z","caller":"traceutil/trace.go:171","msg":"trace[643673300] range","detail":"{range_begin:/registry/events/default/instance.18b108d1a2475795; range_end:; response_count:1; response_revision:194; }","duration":"410.513065ms","start":"2026-05-19T17:50:35.337106Z","end":"2026-05-19T17:50:35.747619Z","steps":["trace[643673300] 'agreement among raft nodes before linearized reading' (duration: 410.222709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:35.747692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:35.337092Z","time spent":"410.590126ms","remote":"127.0.0.1:36318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":676,"request content":"key:\"/registry/events/default/instance.18b108d1a2475795\" "} {"level":"warn","ts":"2026-05-19T17:50:35.747661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.197918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:50:35.747919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.921165ms","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-19T17:50:35.748463Z","caller":"traceutil/trace.go:171","msg":"trace[1517865148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:194; }","duration":"185.507987ms","start":"2026-05-19T17:50:35.562942Z","end":"2026-05-19T17:50:35.74845Z","steps":["trace[1517865148] 'agreement among raft nodes before linearized reading' (duration: 184.401844ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:35.747912Z","caller":"traceutil/trace.go:171","msg":"trace[1901796065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:194; }","duration":"474.578416ms","start":"2026-05-19T17:50:35.273298Z","end":"2026-05-19T17:50:35.747876Z","steps":["trace[1901796065] 'agreement among raft nodes before linearized reading' (duration: 473.858641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:35.748784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:35.27327Z","time spent":"475.446844ms","remote":"127.0.0.1:36268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:50:36.020754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.085712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:50:36.021214Z","caller":"traceutil/trace.go:171","msg":"trace[1461627022] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"268.977123ms","start":"2026-05-19T17:50:35.752168Z","end":"2026-05-19T17:50:36.021146Z","steps":["trace[1461627022] 'process raft request' (duration: 143.386981ms)","trace[1461627022] 'compare' (duration: 124.942629ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:36.021249Z","caller":"traceutil/trace.go:171","msg":"trace[1630278445] linearizableReadLoop","detail":"{readStateIndex:202; appliedIndex:200; }","duration":"263.706637ms","start":"2026-05-19T17:50:35.75752Z","end":"2026-05-19T17:50:36.021227Z","steps":["trace[1630278445] 'read index received' (duration: 137.977382ms)","trace[1630278445] 'applied index is now lower than readState.Index' (duration: 125.727765ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:36.021371Z","caller":"traceutil/trace.go:171","msg":"trace[1092398425] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"268.540326ms","start":"2026-05-19T17:50:35.752771Z","end":"2026-05-19T17:50:36.021311Z","steps":["trace[1092398425] 'process raft request' (duration: 268.112037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:36.021537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.014933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18b108d1a24772d9\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-05-19T17:50:36.021627Z","caller":"traceutil/trace.go:171","msg":"trace[1841313177] range","detail":"{range_begin:/registry/events/default/instance.18b108d1a24772d9; range_end:; response_count:1; response_revision:197; }","duration":"264.140906ms","start":"2026-05-19T17:50:35.757474Z","end":"2026-05-19T17:50:36.021615Z","steps":["trace[1841313177] 'agreement among raft nodes before linearized reading' (duration: 263.826699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:36.076132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.63328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:50:36.076168Z","caller":"traceutil/trace.go:171","msg":"trace[931015893] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"317.84709ms","start":"2026-05-19T17:50:35.758271Z","end":"2026-05-19T17:50:36.076118Z","steps":["trace[931015893] 'process raft request' (duration: 317.596054ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:36.076241Z","caller":"traceutil/trace.go:171","msg":"trace[2011188585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:198; }","duration":"303.791994ms","start":"2026-05-19T17:50:35.772427Z","end":"2026-05-19T17:50:36.076219Z","steps":["trace[2011188585] 'agreement among raft nodes before linearized reading' (duration: 303.588979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:36.07629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:35.772413Z","time spent":"303.869216ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:50:36.076354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:35.758257Z","time spent":"318.007003ms","remote":"127.0.0.1:36644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:36.378225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.289679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:50:36.378409Z","caller":"traceutil/trace.go:171","msg":"trace[1625121964] linearizableReadLoop","detail":"{readStateIndex:204; appliedIndex:203; }","duration":"299.237803ms","start":"2026-05-19T17:50:36.079149Z","end":"2026-05-19T17:50:36.378387Z","steps":["trace[1625121964] 'read index received' (duration: 123.628927ms)","trace[1625121964] 'applied index is now lower than readState.Index' (duration: 175.606776ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:36.378579Z","caller":"traceutil/trace.go:171","msg":"trace[1679831218] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"354.229322ms","start":"2026-05-19T17:50:36.024297Z","end":"2026-05-19T17:50:36.378526Z","steps":["trace[1679831218] 'process raft request' (duration: 178.530545ms)","trace[1679831218] 'compare' (duration: 175.139866ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:36.378743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.704112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:50:36.37879Z","caller":"traceutil/trace.go:171","msg":"trace[357184514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:199; }","duration":"105.789754ms","start":"2026-05-19T17:50:36.272988Z","end":"2026-05-19T17:50:36.378778Z","steps":["trace[357184514] 'agreement among raft nodes before linearized reading' (duration: 105.714083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:36.378799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.55187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:50:36.378957Z","caller":"traceutil/trace.go:171","msg":"trace[437035579] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replication-controller; range_end:; response_count:0; response_revision:199; }","duration":"299.821444ms","start":"2026-05-19T17:50:36.07911Z","end":"2026-05-19T17:50:36.378931Z","steps":["trace[437035579] 'agreement among raft nodes before linearized reading' (duration: 299.506767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:36.378982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:36.024271Z","time spent":"354.412758ms","remote":"127.0.0.1:36318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:50:36.786361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.419762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:50:36.786488Z","caller":"traceutil/trace.go:171","msg":"trace[1479585856] linearizableReadLoop","detail":"{readStateIndex:206; appliedIndex:205; }","duration":"343.161037ms","start":"2026-05-19T17:50:36.443309Z","end":"2026-05-19T17:50:36.78647Z","steps":["trace[1479585856] 'read index received' (duration: 56.456529ms)","trace[1479585856] 'applied index is now lower than readState.Index' (duration: 286.703088ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:36.786598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.294349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:50:36.786571Z","caller":"traceutil/trace.go:171","msg":"trace[719736220] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"401.415444ms","start":"2026-05-19T17:50:36.385107Z","end":"2026-05-19T17:50:36.786523Z","steps":["trace[719736220] 'process raft request' (duration: 114.727306ms)","trace[719736220] 'compare' (duration: 286.30185ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:36.786675Z","caller":"traceutil/trace.go:171","msg":"trace[590965714] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:resourcequota-controller; range_end:; response_count:0; response_revision:201; }","duration":"343.366291ms","start":"2026-05-19T17:50:36.443293Z","end":"2026-05-19T17:50:36.78666Z","steps":["trace[590965714] 'agreement among raft nodes before linearized reading' (duration: 343.251839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:36.786754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:36.443197Z","time spent":"343.544924ms","remote":"127.0.0.1:36644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/system:controller:resourcequota-controller\" "} {"level":"warn","ts":"2026-05-19T17:50:36.78678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:36.385086Z","time spent":"401.574226ms","remote":"127.0.0.1:36318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:36.788798Z","caller":"traceutil/trace.go:171","msg":"trace[1042396611] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"313.507804ms","start":"2026-05-19T17:50:36.475246Z","end":"2026-05-19T17:50:36.788753Z","steps":["trace[1042396611] 'process raft request' (duration: 313.31101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:36.789026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.683651ms","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-19T17:50:36.789014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:36.475225Z","time spent":"313.691618ms","remote":"127.0.0.1:36426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:50:36.789087Z","caller":"traceutil/trace.go:171","msg":"trace[154468966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:202; }","duration":"130.807914ms","start":"2026-05-19T17:50:36.658265Z","end":"2026-05-19T17:50:36.789073Z","steps":["trace[154468966] 'agreement among raft nodes before linearized reading' (duration: 130.367455ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:37.169844Z","caller":"traceutil/trace.go:171","msg":"trace[242662583] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"128.796721ms","start":"2026-05-19T17:50:37.041015Z","end":"2026-05-19T17:50:37.169812Z","steps":["trace[242662583] 'process raft request' (duration: 128.73552ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:37.169914Z","caller":"traceutil/trace.go:171","msg":"trace[2112279503] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"129.826281ms","start":"2026-05-19T17:50:37.040043Z","end":"2026-05-19T17:50:37.169869Z","steps":["trace[2112279503] 'process raft request' (duration: 33.541725ms)","trace[2112279503] 'compare' (duration: 95.9653ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:37.668466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.314876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:50:37.668575Z","caller":"traceutil/trace.go:171","msg":"trace[132666932] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:0; response_revision:235; }","duration":"139.52969ms","start":"2026-05-19T17:50:37.529022Z","end":"2026-05-19T17:50:37.668551Z","steps":["trace[132666932] 'range keys from in-memory index tree' (duration: 139.191633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:39.376735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410333105s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:50:39.376865Z","caller":"traceutil/trace.go:171","msg":"trace[1024469785] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:244; }","duration":"1.364705742s","start":"2026-05-19T17:50:38.012139Z","end":"2026-05-19T17:50:39.376845Z","steps":["trace[1024469785] 'read index received' (duration: 49.241µs)","trace[1024469785] 'applied index is now lower than readState.Index' (duration: 1.364655041s)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:39.376912Z","caller":"traceutil/trace.go:171","msg":"trace[1356575855] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"1.494512424s","start":"2026-05-19T17:50:37.882359Z","end":"2026-05-19T17:50:39.376871Z","steps":["trace[1356575855] 'process raft request' (duration: 83.956944ms)","trace[1356575855] 'compare' (duration: 1.410143091s)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:39.377063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:37.882324Z","time spent":"1.494663697s","remote":"127.0.0.1:36612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":838,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:39.377124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364922397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:50:39.377245Z","caller":"traceutil/trace.go:171","msg":"trace[491806502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:240; }","duration":"1.365176452s","start":"2026-05-19T17:50:38.012052Z","end":"2026-05-19T17:50:39.377228Z","steps":["trace[491806502] 'agreement among raft nodes before linearized reading' (duration: 1.364878086s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:39.377335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:38.012027Z","time spent":"1.365291654s","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T17:50:39.381345Z","caller":"traceutil/trace.go:171","msg":"trace[1773154584] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"171.543271ms","start":"2026-05-19T17:50:39.209784Z","end":"2026-05-19T17:50:39.381327Z","steps":["trace[1773154584] 'process raft request' (duration: 171.301136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:39.381418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.778633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-05-19T17:50:39.381446Z","caller":"traceutil/trace.go:171","msg":"trace[1871194754] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:241; }","duration":"166.819254ms","start":"2026-05-19T17:50:39.214615Z","end":"2026-05-19T17:50:39.381434Z","steps":["trace[1871194754] 'agreement among raft nodes before linearized reading' (duration: 166.772204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:39.381362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.809323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:50:39.381385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355717989s","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-19T17:50:39.381488Z","caller":"traceutil/trace.go:171","msg":"trace[371341598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:241; }","duration":"897.002217ms","start":"2026-05-19T17:50:38.484474Z","end":"2026-05-19T17:50:39.381476Z","steps":["trace[371341598] 'agreement among raft nodes before linearized reading' (duration: 896.813494ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:39.381541Z","caller":"traceutil/trace.go:171","msg":"trace[1840760917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:241; }","duration":"1.355922022s","start":"2026-05-19T17:50:38.025599Z","end":"2026-05-19T17:50:39.381521Z","steps":["trace[1840760917] 'agreement among raft nodes before linearized reading' (duration: 1.355682097s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:39.38157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.687418ms","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-19T17:50:39.3816Z","caller":"traceutil/trace.go:171","msg":"trace[1103638571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:241; }","duration":"575.76223ms","start":"2026-05-19T17:50:38.805829Z","end":"2026-05-19T17:50:39.381591Z","steps":["trace[1103638571] 'agreement among raft nodes before linearized reading' (duration: 575.704878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:39.381622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:38.805784Z","time spent":"575.833051ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T17:50:39.381618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:38.025588Z","time spent":"1.355987984s","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:50:39.381648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:38.484388Z","time spent":"897.18517ms","remote":"127.0.0.1:36268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:50:39.381771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35621613s","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-19T17:50:39.381808Z","caller":"traceutil/trace.go:171","msg":"trace[902338369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:241; }","duration":"1.356272791s","start":"2026-05-19T17:50:38.025526Z","end":"2026-05-19T17:50:39.381798Z","steps":["trace[902338369] 'agreement among raft nodes before linearized reading' (duration: 1.356205379s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:39.381832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:38.02551Z","time spent":"1.356316891s","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T17:50:41.300404Z","caller":"traceutil/trace.go:171","msg":"trace[1178845809] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"124.04036ms","start":"2026-05-19T17:50:41.176336Z","end":"2026-05-19T17:50:41.300376Z","steps":["trace[1178845809] 'process raft request' (duration: 123.872627ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:41.375883Z","caller":"traceutil/trace.go:171","msg":"trace[142481584] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"155.354106ms","start":"2026-05-19T17:50:41.220502Z","end":"2026-05-19T17:50:41.375856Z","steps":["trace[142481584] 'process raft request' (duration: 155.03875ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:42.15973Z","caller":"traceutil/trace.go:171","msg":"trace[1447014826] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"109.369963ms","start":"2026-05-19T17:50:42.050331Z","end":"2026-05-19T17:50:42.159701Z","steps":["trace[1447014826] 'process raft request' (duration: 109.144309ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:42.159811Z","caller":"traceutil/trace.go:171","msg":"trace[520058047] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"109.371283ms","start":"2026-05-19T17:50:42.050412Z","end":"2026-05-19T17:50:42.159784Z","steps":["trace[520058047] 'process raft request' (duration: 109.180699ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:42.160047Z","caller":"traceutil/trace.go:171","msg":"trace[1391607689] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"108.694979ms","start":"2026-05-19T17:50:42.051342Z","end":"2026-05-19T17:50:42.160037Z","steps":["trace[1391607689] 'process raft request' (duration: 108.308381ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:42.160166Z","caller":"traceutil/trace.go:171","msg":"trace[1124297369] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"109.081306ms","start":"2026-05-19T17:50:42.051076Z","end":"2026-05-19T17:50:42.160157Z","steps":["trace[1124297369] 'process raft request' (duration: 108.547756ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:42.160281Z","caller":"traceutil/trace.go:171","msg":"trace[701504824] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"108.653888ms","start":"2026-05-19T17:50:42.051606Z","end":"2026-05-19T17:50:42.16026Z","steps":["trace[701504824] 'process raft request' (duration: 108.089376ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:42.479934Z","caller":"traceutil/trace.go:171","msg":"trace[394769366] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"158.829186ms","start":"2026-05-19T17:50:42.321044Z","end":"2026-05-19T17:50:42.479873Z","steps":["trace[394769366] 'process raft request' (duration: 59.662224ms)","trace[394769366] 'compare' (duration: 98.833935ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:43.416124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.77425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:50:43.416393Z","caller":"traceutil/trace.go:171","msg":"trace[1387954871] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"296.667285ms","start":"2026-05-19T17:50:43.119672Z","end":"2026-05-19T17:50:43.416339Z","steps":["trace[1387954871] 'process raft request' (duration: 296.585283ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:43.416431Z","caller":"traceutil/trace.go:171","msg":"trace[1398364318] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"311.968663ms","start":"2026-05-19T17:50:43.104419Z","end":"2026-05-19T17:50:43.416387Z","steps":["trace[1398364318] 'process raft request' (duration: 77.825415ms)","trace[1398364318] 'compare' (duration: 233.624957ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:43.416633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:43.104381Z","time spent":"312.133105ms","remote":"127.0.0.1:36426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:43.731972Z","caller":"traceutil/trace.go:171","msg":"trace[1066862728] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"123.532073ms","start":"2026-05-19T17:50:43.608405Z","end":"2026-05-19T17:50:43.731938Z","steps":["trace[1066862728] 'process raft request' (duration: 123.40934ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:43.732333Z","caller":"traceutil/trace.go:171","msg":"trace[1618719870] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"129.576399ms","start":"2026-05-19T17:50:43.6027Z","end":"2026-05-19T17:50:43.732276Z","steps":["trace[1618719870] 'process raft request' (duration: 128.855893ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:43.732014Z","caller":"traceutil/trace.go:171","msg":"trace[1716060975] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"130.986947ms","start":"2026-05-19T17:50:43.600986Z","end":"2026-05-19T17:50:43.731973Z","steps":["trace[1716060975] 'process raft request' (duration: 120.959579ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:43.733298Z","caller":"traceutil/trace.go:171","msg":"trace[128528778] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"128.971225ms","start":"2026-05-19T17:50:43.604295Z","end":"2026-05-19T17:50:43.733266Z","steps":["trace[128528778] 'process raft request' (duration: 127.350121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:44.729578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.594778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:50:44.72959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.343494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T17:50:44.729694Z","caller":"traceutil/trace.go:171","msg":"trace[1490338961] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:349; }","duration":"359.783382ms","start":"2026-05-19T17:50:44.369882Z","end":"2026-05-19T17:50:44.729665Z","steps":["trace[1490338961] 'range keys from in-memory index tree' (duration: 359.414204ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:44.729725Z","caller":"traceutil/trace.go:171","msg":"trace[229783625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:349; }","duration":"171.556888ms","start":"2026-05-19T17:50:44.558134Z","end":"2026-05-19T17:50:44.72969Z","steps":["trace[229783625] 'range keys from in-memory index tree' (duration: 171.161659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:44.729759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:44.369855Z","time spent":"359.889784ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"info","ts":"2026-05-19T17:50:45.415498Z","caller":"traceutil/trace.go:171","msg":"trace[1457001580] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"681.108685ms","start":"2026-05-19T17:50:44.734356Z","end":"2026-05-19T17:50:45.415465Z","steps":["trace[1457001580] 'process raft request' (duration: 680.90492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:45.415741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:44.734325Z","time spent":"681.281808ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:50:46.08901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.495069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:50:46.08911Z","caller":"traceutil/trace.go:171","msg":"trace[407196914] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"445.340598ms","start":"2026-05-19T17:50:45.643753Z","end":"2026-05-19T17:50:46.089094Z","steps":["trace[407196914] 'read index received' (duration: 121.683456ms)","trace[407196914] 'applied index is now lower than readState.Index' (duration: 323.655902ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:46.089147Z","caller":"traceutil/trace.go:171","msg":"trace[2026281250] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"1.352865612s","start":"2026-05-19T17:50:44.73624Z","end":"2026-05-19T17:50:46.089105Z","steps":["trace[2026281250] 'process raft request' (duration: 1.02920959s)","trace[2026281250] 'compare' (duration: 323.173872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:46.089253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.502481ms","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-19T17:50:46.089281Z","caller":"traceutil/trace.go:171","msg":"trace[1682594463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:351; }","duration":"445.586473ms","start":"2026-05-19T17:50:45.643687Z","end":"2026-05-19T17:50:46.089273Z","steps":["trace[1682594463] 'agreement among raft nodes before linearized reading' (duration: 445.487831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:46.089305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:45.643652Z","time spent":"445.647904ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T17:50:46.089347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:44.736227Z","time spent":"1.353023516s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:50:46.089465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.726578ms","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-19T17:50:46.089489Z","caller":"traceutil/trace.go:171","msg":"trace[1575748807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:351; }","duration":"351.789128ms","start":"2026-05-19T17:50:45.737694Z","end":"2026-05-19T17:50:46.089484Z","steps":["trace[1575748807] 'agreement among raft nodes before linearized reading' (duration: 351.746197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:46.089506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:45.737676Z","time spent":"351.827209ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:50:46.089621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.493612ms","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-19T17:50:46.089638Z","caller":"traceutil/trace.go:171","msg":"trace[1225205624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:351; }","duration":"351.530202ms","start":"2026-05-19T17:50:45.738103Z","end":"2026-05-19T17:50:46.089633Z","steps":["trace[1225205624] 'agreement among raft nodes before linearized reading' (duration: 351.495581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:46.089654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:45.738093Z","time spent":"351.557602ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T17:50:46.597305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738095899591961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:50:47.09842Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738095899591961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:50:47.5991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738095899591961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:50:48.099671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738095899591961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:50:48.168346Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.309524685s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:50:48.21184Z","caller":"traceutil/trace.go:171","msg":"trace[844892533] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"2.117185817s","start":"2026-05-19T17:50:46.09462Z","end":"2026-05-19T17:50:48.211806Z","steps":["trace[844892533] 'process raft request' (duration: 2.074052711s)","trace[844892533] 'compare' (duration: 42.507612ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:48.212017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:46.094582Z","time spent":"2.11734164s","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:48.223996Z","caller":"traceutil/trace.go:171","msg":"trace[772611093] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"2.128989314s","start":"2026-05-19T17:50:46.094975Z","end":"2026-05-19T17:50:48.223964Z","steps":["trace[772611093] 'process raft request' (duration: 2.128672198s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.224207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:46.094964Z","time spent":"2.129130418s","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:48.224206Z","caller":"traceutil/trace.go:171","msg":"trace[1402836816] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:364; }","duration":"2.127597226s","start":"2026-05-19T17:50:46.096524Z","end":"2026-05-19T17:50:48.224121Z","steps":["trace[1402836816] 'read index received' (duration: 2.072162642s)","trace[1402836816] 'applied index is now lower than readState.Index' (duration: 55.432924ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:48.224363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.127822591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-19T17:50:48.22442Z","caller":"traceutil/trace.go:171","msg":"trace[206138816] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"2.129225099s","start":"2026-05-19T17:50:46.095091Z","end":"2026-05-19T17:50:48.224316Z","steps":["trace[206138816] 'process raft request' (duration: 2.128733039s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.224489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505347213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:50:48.224555Z","caller":"traceutil/trace.go:171","msg":"trace[162568311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:354; }","duration":"1.505481976s","start":"2026-05-19T17:50:46.71906Z","end":"2026-05-19T17:50:48.224542Z","steps":["trace[162568311] 'agreement among raft nodes before linearized reading' (duration: 1.505337793s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.22461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:46.719031Z","time spent":"1.505547507s","remote":"127.0.0.1:36268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:50:48.22462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:46.095087Z","time spent":"2.129420803s","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:50:48.224633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.120421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-05-19T17:50:48.22467Z","caller":"traceutil/trace.go:171","msg":"trace[201347456] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:354; }","duration":"109.216533ms","start":"2026-05-19T17:50:48.115444Z","end":"2026-05-19T17:50:48.224661Z","steps":["trace[201347456] 'agreement among raft nodes before linearized reading' (duration: 109.050489ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:48.224446Z","caller":"traceutil/trace.go:171","msg":"trace[929847348] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:354; }","duration":"2.127921583s","start":"2026-05-19T17:50:46.09651Z","end":"2026-05-19T17:50:48.224432Z","steps":["trace[929847348] 'agreement among raft nodes before linearized reading' (duration: 2.12777132s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.224779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:46.096506Z","time spent":"2.12825827s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-05-19T17:50:48.224898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.391087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T17:50:48.224979Z","caller":"traceutil/trace.go:171","msg":"trace[1701580391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:354; }","duration":"803.55372ms","start":"2026-05-19T17:50:47.421403Z","end":"2026-05-19T17:50:48.224957Z","steps":["trace[1701580391] 'agreement among raft nodes before linearized reading' (duration: 803.349536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.225033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.421373Z","time spent":"803.652522ms","remote":"127.0.0.1:36534","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-19T17:50:48.995191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.345826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:50:48.995357Z","caller":"traceutil/trace.go:171","msg":"trace[120558911] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:412; }","duration":"245.728558ms","start":"2026-05-19T17:50:48.749605Z","end":"2026-05-19T17:50:48.995334Z","steps":["trace[120558911] 'read index received' (duration: 43.998323ms)","trace[120558911] 'applied index is now lower than readState.Index' (duration: 201.727585ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:48.995482Z","caller":"traceutil/trace.go:171","msg":"trace[347297193] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"317.885472ms","start":"2026-05-19T17:50:48.67758Z","end":"2026-05-19T17:50:48.995466Z","steps":["trace[347297193] 'process raft request' (duration: 116.111927ms)","trace[347297193] 'compare' (duration: 201.153183ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:48.995562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:48.677565Z","time spent":"317.961684ms","remote":"127.0.0.1:36750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:50:48.995702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.087605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-05-19T17:50:48.995813Z","caller":"traceutil/trace.go:171","msg":"trace[1281990679] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:400; }","duration":"115.28812ms","start":"2026-05-19T17:50:48.880493Z","end":"2026-05-19T17:50:48.995781Z","steps":["trace[1281990679] 'agreement among raft nodes before linearized reading' (duration: 115.067695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.995958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.34386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:50:48.996026Z","caller":"traceutil/trace.go:171","msg":"trace[68721190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"246.415533ms","start":"2026-05-19T17:50:48.749593Z","end":"2026-05-19T17:50:48.996008Z","steps":["trace[68721190] 'agreement among raft nodes before linearized reading' (duration: 246.305989ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:49.21204Z","caller":"traceutil/trace.go:171","msg":"trace[411400279] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"151.666132ms","start":"2026-05-19T17:50:49.060336Z","end":"2026-05-19T17:50:49.212003Z","steps":["trace[411400279] 'read index received' (duration: 147.610356ms)","trace[411400279] 'applied index is now lower than readState.Index' (duration: 4.048856ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:49.212221Z","caller":"traceutil/trace.go:171","msg":"trace[1372207196] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"206.350491ms","start":"2026-05-19T17:50:49.005806Z","end":"2026-05-19T17:50:49.212157Z","steps":["trace[1372207196] 'process raft request' (duration: 202.220214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:49.212343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.967238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-05-19T17:50:49.212434Z","caller":"traceutil/trace.go:171","msg":"trace[286310630] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:402; }","duration":"152.118011ms","start":"2026-05-19T17:50:49.060296Z","end":"2026-05-19T17:50:49.212414Z","steps":["trace[286310630] 'agreement among raft nodes before linearized reading' (duration: 151.880706ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:53.941582Z","caller":"traceutil/trace.go:171","msg":"trace[1068164938] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"936.543564ms","start":"2026-05-19T17:50:53.005009Z","end":"2026-05-19T17:50:53.941553Z","steps":["trace[1068164938] 'process raft request' (duration: 936.37795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:53.941863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:53.004981Z","time spent":"936.777378ms","remote":"127.0.0.1:36426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:54.491859Z","caller":"traceutil/trace.go:171","msg":"trace[1409592631] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"101.029188ms","start":"2026-05-19T17:50:54.390799Z","end":"2026-05-19T17:50:54.491828Z","steps":["trace[1409592631] 'process raft request' (duration: 24.877939ms)","trace[1409592631] 'compare' (duration: 75.918294ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:54.492193Z","caller":"traceutil/trace.go:171","msg":"trace[1308777804] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"101.207412ms","start":"2026-05-19T17:50:54.390897Z","end":"2026-05-19T17:50:54.492105Z","steps":["trace[1308777804] 'process raft request' (duration: 100.873785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:55.406107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.676885ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T17:50:58.640012Z","caller":"traceutil/trace.go:171","msg":"trace[1467547083] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"174.03111ms","start":"2026-05-19T17:50:58.465947Z","end":"2026-05-19T17:50:58.639978Z","steps":["trace[1467547083] 'process raft request' (duration: 164.349313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:14.250949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738095899592280,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T17:51:14.352158Z","caller":"traceutil/trace.go:171","msg":"trace[2089516579] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"601.776025ms","start":"2026-05-19T17:51:13.750366Z","end":"2026-05-19T17:51:14.352142Z","steps":["trace[2089516579] 'read index received' (duration: 601.234673ms)","trace[2089516579] 'applied index is now lower than readState.Index' (duration: 540.712µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:14.352252Z","caller":"traceutil/trace.go:171","msg":"trace[2002238932] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"894.1941ms","start":"2026-05-19T17:51:13.458021Z","end":"2026-05-19T17:51:14.352215Z","steps":["trace[2002238932] 'process raft request' (duration: 893.868462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:14.352396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:13.458002Z","time spent":"894.329453ms","remote":"127.0.0.1:36336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317296,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:51:14.352512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.902638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:14.35255Z","caller":"traceutil/trace.go:171","msg":"trace[829057093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"602.207935ms","start":"2026-05-19T17:51:13.750331Z","end":"2026-05-19T17:51:14.352539Z","steps":["trace[829057093] 'agreement among raft nodes before linearized reading' (duration: 601.885838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:14.352644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:13.750318Z","time spent":"602.252475ms","remote":"127.0.0.1:36268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T17:51:16.229489Z","caller":"traceutil/trace.go:171","msg":"trace[142096272] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"211.782714ms","start":"2026-05-19T17:51:16.017681Z","end":"2026-05-19T17:51:16.229464Z","steps":["trace[142096272] 'process raft request' (duration: 211.5903ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:16.704879Z","caller":"traceutil/trace.go:171","msg":"trace[738387308] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"664.567706ms","start":"2026-05-19T17:51:16.040273Z","end":"2026-05-19T17:51:16.70484Z","steps":["trace[738387308] 'process raft request' (duration: 664.324731ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:16.704996Z","caller":"traceutil/trace.go:171","msg":"trace[1469641491] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"659.662868ms","start":"2026-05-19T17:51:16.045302Z","end":"2026-05-19T17:51:16.704965Z","steps":["trace[1469641491] 'process raft request' (duration: 659.424522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:16.705051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.040251Z","time spent":"664.7256ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:51:16.705146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.04529Z","time spent":"659.79303ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:51:16.705229Z","caller":"traceutil/trace.go:171","msg":"trace[942263808] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"670.050556ms","start":"2026-05-19T17:51:16.035168Z","end":"2026-05-19T17:51:16.705219Z","steps":["trace[942263808] 'process raft request' (duration: 669.29367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:16.705275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.035148Z","time spent":"670.105277ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:51:16.705295Z","caller":"traceutil/trace.go:171","msg":"trace[1209126809] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"657.632412ms","start":"2026-05-19T17:51:16.047506Z","end":"2026-05-19T17:51:16.705138Z","steps":["trace[1209126809] 'process raft request' (duration: 657.302915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:16.70548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.047494Z","time spent":"657.93363ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:51:16.705339Z","caller":"traceutil/trace.go:171","msg":"trace[1166822901] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"662.375158ms","start":"2026-05-19T17:51:16.04292Z","end":"2026-05-19T17:51:16.705296Z","steps":["trace[1166822901] 'process raft request' (duration: 661.730094ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:16.705612Z","caller":"traceutil/trace.go:171","msg":"trace[1565223731] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"657.741436ms","start":"2026-05-19T17:51:16.047727Z","end":"2026-05-19T17:51:16.705469Z","steps":["trace[1565223731] 'process raft request' (duration: 657.139462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:16.70568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.042903Z","time spent":"662.709915ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:51:16.705778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.047717Z","time spent":"657.988641ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:51:16.706289Z","caller":"traceutil/trace.go:171","msg":"trace[2001564834] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"658.014921ms","start":"2026-05-19T17:51:16.048262Z","end":"2026-05-19T17:51:16.706277Z","steps":["trace[2001564834] 'process raft request' (duration: 656.678532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:16.70635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.048247Z","time spent":"658.076702ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:51:16.706947Z","caller":"traceutil/trace.go:171","msg":"trace[232240302] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"645.662198ms","start":"2026-05-19T17:51:16.061253Z","end":"2026-05-19T17:51:16.706915Z","steps":["trace[232240302] 'process raft request' (duration: 644.962363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:16.707085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.061234Z","time spent":"645.788431ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:51:16.707117Z","caller":"traceutil/trace.go:171","msg":"trace[184136055] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"486.800472ms","start":"2026-05-19T17:51:16.220268Z","end":"2026-05-19T17:51:16.707068Z","steps":["trace[184136055] 'process raft request' (duration: 486.70797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:16.707221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.220239Z","time spent":"486.913794ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:51:16.707375Z","caller":"traceutil/trace.go:171","msg":"trace[1196279918] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"606.581136ms","start":"2026-05-19T17:51:16.100754Z","end":"2026-05-19T17:51:16.707335Z","steps":["trace[1196279918] 'process raft request' (duration: 606.091524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:16.707456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.100732Z","time spent":"606.673349ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:51:16.707528Z","caller":"traceutil/trace.go:171","msg":"trace[958542253] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"596.631736ms","start":"2026-05-19T17:51:16.110885Z","end":"2026-05-19T17:51:16.707517Z","steps":["trace[958542253] 'process raft request' (duration: 596.021302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:16.70759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.110862Z","time spent":"596.703828ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:51:16.783984Z","caller":"traceutil/trace.go:171","msg":"trace[632649826] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"549.924156ms","start":"2026-05-19T17:51:16.234041Z","end":"2026-05-19T17:51:16.783965Z","steps":["trace[632649826] 'process raft request' (duration: 549.723191ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:16.784006Z","caller":"traceutil/trace.go:171","msg":"trace[1060631517] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:563; }","duration":"230.426345ms","start":"2026-05-19T17:51:16.553554Z","end":"2026-05-19T17:51:16.783981Z","steps":["trace[1060631517] 'read index received' (duration: 151.248118ms)","trace[1060631517] 'applied index is now lower than readState.Index' (duration: 79.177347ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:16.784078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.234026Z","time spent":"550.008387ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:51:16.784236Z","caller":"traceutil/trace.go:171","msg":"trace[1822333368] transaction","detail":"{read_only:false; number_of_response:1; response_revision:551; }","duration":"546.626743ms","start":"2026-05-19T17:51:16.237541Z","end":"2026-05-19T17:51:16.784167Z","steps":["trace[1822333368] 'process raft request' (duration: 546.406438ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:16.784326Z","caller":"traceutil/trace.go:171","msg":"trace[1725090594] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"548.226268ms","start":"2026-05-19T17:51:16.236087Z","end":"2026-05-19T17:51:16.784313Z","steps":["trace[1725090594] 'process raft request' (duration: 547.786589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:16.784353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.798213ms","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-19T17:51:16.78439Z","caller":"traceutil/trace.go:171","msg":"trace[631520348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"230.876355ms","start":"2026-05-19T17:51:16.553506Z","end":"2026-05-19T17:51:16.784382Z","steps":["trace[631520348] 'agreement among raft nodes before linearized reading' (duration: 230.736682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:16.784435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.236074Z","time spent":"548.286929ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:16.784507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:16.237517Z","time spent":"546.810106ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:16.963142Z","caller":"traceutil/trace.go:171","msg":"trace[2101990014] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"173.213383ms","start":"2026-05-19T17:51:16.789891Z","end":"2026-05-19T17:51:16.963104Z","steps":["trace[2101990014] 'process raft request' (duration: 160.314438ms)","trace[2101990014] 'compare' (duration: 12.66248ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:16.96353Z","caller":"traceutil/trace.go:171","msg":"trace[1869271295] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"169.156663ms","start":"2026-05-19T17:51:16.794358Z","end":"2026-05-19T17:51:16.963514Z","steps":["trace[1869271295] 'process raft request' (duration: 168.668292ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:16.963561Z","caller":"traceutil/trace.go:171","msg":"trace[1626241809] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"157.140517ms","start":"2026-05-19T17:51:16.80639Z","end":"2026-05-19T17:51:16.96353Z","steps":["trace[1626241809] 'process raft request' (duration: 156.975963ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:16.963809Z","caller":"traceutil/trace.go:171","msg":"trace[468652344] transaction","detail":"{read_only:false; number_of_response:0; response_revision:563; }","duration":"156.075415ms","start":"2026-05-19T17:51:16.807724Z","end":"2026-05-19T17:51:16.963799Z","steps":["trace[468652344] 'process raft request' (duration: 155.751528ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:16.964064Z","caller":"traceutil/trace.go:171","msg":"trace[57611143] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:582; }","duration":"156.81731ms","start":"2026-05-19T17:51:16.807221Z","end":"2026-05-19T17:51:16.964039Z","steps":["trace[57611143] 'read index received' (duration: 143.100067ms)","trace[57611143] 'applied index is now lower than readState.Index' (duration: 13.716243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:16.964267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.897523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:16.964325Z","caller":"traceutil/trace.go:171","msg":"trace[145228286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"156.995374ms","start":"2026-05-19T17:51:16.807313Z","end":"2026-05-19T17:51:16.964309Z","steps":["trace[145228286] 'agreement among raft nodes before linearized reading' (duration: 156.878581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:16.964578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.338252ms","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-19T17:51:16.964663Z","caller":"traceutil/trace.go:171","msg":"trace[1521116080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"157.435474ms","start":"2026-05-19T17:51:16.807185Z","end":"2026-05-19T17:51:16.96462Z","steps":["trace[1521116080] 'agreement among raft nodes before linearized reading' (duration: 157.313721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:17.700547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.943359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:51:17.700996Z","caller":"traceutil/trace.go:171","msg":"trace[1788091986] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"305.541891ms","start":"2026-05-19T17:51:17.39542Z","end":"2026-05-19T17:51:17.700962Z","steps":["trace[1788091986] 'process raft request' (duration: 190.081001ms)","trace[1788091986] 'compare' (duration: 114.760285ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:17.70118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:17.3954Z","time spent":"305.715855ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:17.705957Z","caller":"traceutil/trace.go:171","msg":"trace[1175175046] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"224.342847ms","start":"2026-05-19T17:51:17.4816Z","end":"2026-05-19T17:51:17.705943Z","steps":["trace[1175175046] 'process raft request' (duration: 223.657852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.056801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.423536ms","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-19T17:51:19.056878Z","caller":"traceutil/trace.go:171","msg":"trace[998878852] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:610; }","duration":"976.529098ms","start":"2026-05-19T17:51:18.080332Z","end":"2026-05-19T17:51:19.056862Z","steps":["trace[998878852] 'range keys from in-memory index tree' (duration: 976.290124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.05705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.080318Z","time spent":"976.58816ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29593,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-19T17:51:19.101301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738095899592422,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T17:51:19.12066Z","caller":"traceutil/trace.go:171","msg":"trace[405486280] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"979.368875ms","start":"2026-05-19T17:51:18.141271Z","end":"2026-05-19T17:51:19.12064Z","steps":["trace[405486280] 'process raft request' (duration: 978.559717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.12078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.141252Z","time spent":"979.461667ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:19.122047Z","caller":"traceutil/trace.go:171","msg":"trace[1286797794] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:634; }","duration":"521.425762ms","start":"2026-05-19T17:51:18.600613Z","end":"2026-05-19T17:51:19.122039Z","steps":["trace[1286797794] 'read index received' (duration: 521.419582ms)","trace[1286797794] 'applied index is now lower than readState.Index' (duration: 5.48µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:19.122134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.434263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.122165Z","caller":"traceutil/trace.go:171","msg":"trace[279400149] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:611; }","duration":"521.480444ms","start":"2026-05-19T17:51:18.600677Z","end":"2026-05-19T17:51:19.122158Z","steps":["trace[279400149] 'agreement among raft nodes before linearized reading' (duration: 521.427802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.122187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.600673Z","time spent":"521.508104ms","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:51:19.122308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.698738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.122334Z","caller":"traceutil/trace.go:171","msg":"trace[961553449] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:611; }","duration":"521.749549ms","start":"2026-05-19T17:51:18.600579Z","end":"2026-05-19T17:51:19.122329Z","steps":["trace[961553449] 'agreement among raft nodes before linearized reading' (duration: 521.712238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.122353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.600564Z","time spent":"521.78411ms","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:51:19.122522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.794036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.122548Z","caller":"traceutil/trace.go:171","msg":"trace[1284944281] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:611; }","duration":"106.832147ms","start":"2026-05-19T17:51:19.01571Z","end":"2026-05-19T17:51:19.122542Z","steps":["trace[1284944281] 'agreement among raft nodes before linearized reading' (duration: 106.794786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.122654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.558644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.122682Z","caller":"traceutil/trace.go:171","msg":"trace[17158570] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:611; }","duration":"107.620055ms","start":"2026-05-19T17:51:19.015056Z","end":"2026-05-19T17:51:19.122676Z","steps":["trace[17158570] 'agreement among raft nodes before linearized reading' (duration: 107.580644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.122758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.750467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.12278Z","caller":"traceutil/trace.go:171","msg":"trace[958651664] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:611; }","duration":"107.773688ms","start":"2026-05-19T17:51:19.015002Z","end":"2026-05-19T17:51:19.122775Z","steps":["trace[958651664] 'agreement among raft nodes before linearized reading' (duration: 107.741517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.122883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.126088ms","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-19T17:51:19.122906Z","caller":"traceutil/trace.go:171","msg":"trace[434114954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"128.15963ms","start":"2026-05-19T17:51:18.994741Z","end":"2026-05-19T17:51:19.122901Z","steps":["trace[434114954] 'agreement among raft nodes before linearized reading' (duration: 128.099228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.123021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.526277ms","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-19T17:51:19.123044Z","caller":"traceutil/trace.go:171","msg":"trace[401474999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"128.565999ms","start":"2026-05-19T17:51:18.994472Z","end":"2026-05-19T17:51:19.123038Z","steps":["trace[401474999] 'agreement among raft nodes before linearized reading' (duration: 128.511557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.123147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.20541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T17:51:19.123169Z","caller":"traceutil/trace.go:171","msg":"trace[1712962308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"142.245421ms","start":"2026-05-19T17:51:18.980918Z","end":"2026-05-19T17:51:19.123164Z","steps":["trace[1712962308] 'agreement among raft nodes before linearized reading' (duration: 142.19152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.123249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.178328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.123271Z","caller":"traceutil/trace.go:171","msg":"trace[649003851] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:611; }","duration":"146.209519ms","start":"2026-05-19T17:51:18.977056Z","end":"2026-05-19T17:51:19.123266Z","steps":["trace[649003851] 'agreement among raft nodes before linearized reading' (duration: 146.176358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.123357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.990985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.123381Z","caller":"traceutil/trace.go:171","msg":"trace[1972254404] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:611; }","duration":"147.037566ms","start":"2026-05-19T17:51:18.976338Z","end":"2026-05-19T17:51:19.123376Z","steps":["trace[1972254404] 'agreement among raft nodes before linearized reading' (duration: 147.005425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.123451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.152859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.123474Z","caller":"traceutil/trace.go:171","msg":"trace[858196336] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:611; }","duration":"147.18859ms","start":"2026-05-19T17:51:18.976279Z","end":"2026-05-19T17:51:19.123468Z","steps":["trace[858196336] 'agreement among raft nodes before linearized reading' (duration: 147.158009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.123571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.999459ms","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-19T17:51:19.123599Z","caller":"traceutil/trace.go:171","msg":"trace[1450341028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"153.051661ms","start":"2026-05-19T17:51:18.97054Z","end":"2026-05-19T17:51:19.123592Z","steps":["trace[1450341028] 'agreement among raft nodes before linearized reading' (duration: 152.993479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.123688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.913099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.123713Z","caller":"traceutil/trace.go:171","msg":"trace[1338705640] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:611; }","duration":"167.96499ms","start":"2026-05-19T17:51:18.955742Z","end":"2026-05-19T17:51:19.123707Z","steps":["trace[1338705640] 'agreement among raft nodes before linearized reading' (duration: 167.930059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.123806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.287107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.123828Z","caller":"traceutil/trace.go:171","msg":"trace[605888864] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:611; }","duration":"168.4002ms","start":"2026-05-19T17:51:18.955423Z","end":"2026-05-19T17:51:19.123823Z","steps":["trace[605888864] 'agreement among raft nodes before linearized reading' (duration: 168.367019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.123901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.477241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.123923Z","caller":"traceutil/trace.go:171","msg":"trace[1964225000] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:611; }","duration":"168.501722ms","start":"2026-05-19T17:51:18.955417Z","end":"2026-05-19T17:51:19.123918Z","steps":["trace[1964225000] 'agreement among raft nodes before linearized reading' (duration: 168.470061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.124052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.51666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.124079Z","caller":"traceutil/trace.go:171","msg":"trace[755625855] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:611; }","duration":"223.574781ms","start":"2026-05-19T17:51:18.900499Z","end":"2026-05-19T17:51:19.124074Z","steps":["trace[755625855] 'agreement among raft nodes before linearized reading' (duration: 223.483069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.124177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.519351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.124201Z","caller":"traceutil/trace.go:171","msg":"trace[357047239] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:611; }","duration":"224.645094ms","start":"2026-05-19T17:51:18.89955Z","end":"2026-05-19T17:51:19.124195Z","steps":["trace[357047239] 'agreement among raft nodes before linearized reading' (duration: 224.612543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.124282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.848299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.124303Z","caller":"traceutil/trace.go:171","msg":"trace[1182599620] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:611; }","duration":"224.884299ms","start":"2026-05-19T17:51:18.899413Z","end":"2026-05-19T17:51:19.124298Z","steps":["trace[1182599620] 'agreement among raft nodes before linearized reading' (duration: 224.851668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.124398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.563398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.12442Z","caller":"traceutil/trace.go:171","msg":"trace[1987733372] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:611; }","duration":"241.61737ms","start":"2026-05-19T17:51:18.882796Z","end":"2026-05-19T17:51:19.124413Z","steps":["trace[1987733372] 'agreement among raft nodes before linearized reading' (duration: 241.586929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.124513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.713914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.124536Z","caller":"traceutil/trace.go:171","msg":"trace[1680548269] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:611; }","duration":"242.777725ms","start":"2026-05-19T17:51:18.881753Z","end":"2026-05-19T17:51:19.124531Z","steps":["trace[1680548269] 'agreement among raft nodes before linearized reading' (duration: 242.746674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.124613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.272536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.12464Z","caller":"traceutil/trace.go:171","msg":"trace[1132678129] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:611; }","duration":"243.326996ms","start":"2026-05-19T17:51:18.881308Z","end":"2026-05-19T17:51:19.124635Z","steps":["trace[1132678129] 'agreement among raft nodes before linearized reading' (duration: 243.287505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.124721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.338886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.12474Z","caller":"traceutil/trace.go:171","msg":"trace[2052036144] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:611; }","duration":"261.371956ms","start":"2026-05-19T17:51:18.863363Z","end":"2026-05-19T17:51:19.124735Z","steps":["trace[2052036144] 'agreement among raft nodes before linearized reading' (duration: 261.340896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.124833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.018611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.124855Z","caller":"traceutil/trace.go:171","msg":"trace[1868877318] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:611; }","duration":"262.054082ms","start":"2026-05-19T17:51:18.862796Z","end":"2026-05-19T17:51:19.12485Z","steps":["trace[1868877318] 'agreement among raft nodes before linearized reading' (duration: 262.021271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.124941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.152345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.124963Z","caller":"traceutil/trace.go:171","msg":"trace[1151034303] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:611; }","duration":"262.197365ms","start":"2026-05-19T17:51:18.86276Z","end":"2026-05-19T17:51:19.124958Z","steps":["trace[1151034303] 'agreement among raft nodes before linearized reading' (duration: 262.166125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.125034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.280652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.125058Z","caller":"traceutil/trace.go:171","msg":"trace[1813150659] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:611; }","duration":"283.303492ms","start":"2026-05-19T17:51:18.841747Z","end":"2026-05-19T17:51:19.125051Z","steps":["trace[1813150659] 'agreement among raft nodes before linearized reading' (duration: 283.273332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.125129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.468896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.12515Z","caller":"traceutil/trace.go:171","msg":"trace[2081921953] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:611; }","duration":"283.507677ms","start":"2026-05-19T17:51:18.841637Z","end":"2026-05-19T17:51:19.125145Z","steps":["trace[2081921953] 'agreement among raft nodes before linearized reading' (duration: 283.477917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.125276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.525577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.125309Z","caller":"traceutil/trace.go:171","msg":"trace[2087322790] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:611; }","duration":"311.563598ms","start":"2026-05-19T17:51:18.813739Z","end":"2026-05-19T17:51:19.125303Z","steps":["trace[2087322790] 'agreement among raft nodes before linearized reading' (duration: 311.517697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.125329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.813695Z","time spent":"311.628919ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-05-19T17:51:19.125427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.041678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.125449Z","caller":"traceutil/trace.go:171","msg":"trace[732653562] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:611; }","duration":"312.09533ms","start":"2026-05-19T17:51:18.813349Z","end":"2026-05-19T17:51:19.125444Z","steps":["trace[732653562] 'agreement among raft nodes before linearized reading' (duration: 312.063459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.125468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.813327Z","time spent":"312.13588ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:51:19.125541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.190262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.125563Z","caller":"traceutil/trace.go:171","msg":"trace[463923270] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:611; }","duration":"312.240793ms","start":"2026-05-19T17:51:18.813317Z","end":"2026-05-19T17:51:19.125558Z","steps":["trace[463923270] 'agreement among raft nodes before linearized reading' (duration: 312.208883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.125581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.813298Z","time spent":"312.277804ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:51:19.125665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.051979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.125691Z","caller":"traceutil/trace.go:171","msg":"trace[787539355] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:611; }","duration":"354.080279ms","start":"2026-05-19T17:51:18.771606Z","end":"2026-05-19T17:51:19.125686Z","steps":["trace[787539355] 'agreement among raft nodes before linearized reading' (duration: 354.040638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.12571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.771585Z","time spent":"354.1198ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-05-19T17:51:19.125809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.222982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.12583Z","caller":"traceutil/trace.go:171","msg":"trace[1785370320] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:611; }","duration":"354.261143ms","start":"2026-05-19T17:51:18.771564Z","end":"2026-05-19T17:51:19.125825Z","steps":["trace[1785370320] 'agreement among raft nodes before linearized reading' (duration: 354.230163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.125848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.771559Z","time spent":"354.284614ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:51:19.125932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.391725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.125958Z","caller":"traceutil/trace.go:171","msg":"trace[1674195256] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:611; }","duration":"354.437447ms","start":"2026-05-19T17:51:18.771512Z","end":"2026-05-19T17:51:19.12595Z","steps":["trace[1674195256] 'agreement among raft nodes before linearized reading' (duration: 354.401326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.125979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.7715Z","time spent":"354.473207ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:51:19.12607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.988315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.126103Z","caller":"traceutil/trace.go:171","msg":"trace[1952531125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"377.026657ms","start":"2026-05-19T17:51:18.74906Z","end":"2026-05-19T17:51:19.126086Z","steps":["trace[1952531125] 'agreement among raft nodes before linearized reading' (duration: 376.993976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.12612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.749052Z","time spent":"377.064468ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:51:19.126212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.059644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.126235Z","caller":"traceutil/trace.go:171","msg":"trace[171309687] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:611; }","duration":"385.082724ms","start":"2026-05-19T17:51:18.741146Z","end":"2026-05-19T17:51:19.126229Z","steps":["trace[171309687] 'agreement among raft nodes before linearized reading' (duration: 385.050313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.126253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.741127Z","time spent":"385.121424ms","remote":"127.0.0.1:40448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-05-19T17:51:19.126345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.375532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.126367Z","caller":"traceutil/trace.go:171","msg":"trace[1200969732] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:611; }","duration":"386.415674ms","start":"2026-05-19T17:51:18.739946Z","end":"2026-05-19T17:51:19.126361Z","steps":["trace[1200969732] 'agreement among raft nodes before linearized reading' (duration: 386.383243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.126386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.739934Z","time spent":"386.446684ms","remote":"127.0.0.1:40448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:51:19.126462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.491505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.126483Z","caller":"traceutil/trace.go:171","msg":"trace[277000021] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:611; }","duration":"386.531836ms","start":"2026-05-19T17:51:18.739946Z","end":"2026-05-19T17:51:19.126478Z","steps":["trace[277000021] 'agreement among raft nodes before linearized reading' (duration: 386.499256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.126501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.739938Z","time spent":"386.557767ms","remote":"127.0.0.1:40448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:51:19.126576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.147511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.126597Z","caller":"traceutil/trace.go:171","msg":"trace[514296161] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:611; }","duration":"402.168632ms","start":"2026-05-19T17:51:18.724423Z","end":"2026-05-19T17:51:19.126592Z","steps":["trace[514296161] 'agreement among raft nodes before linearized reading' (duration: 402.138471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.126614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.724391Z","time spent":"402.218673ms","remote":"127.0.0.1:40436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-19T17:51:19.126718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.606953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.126744Z","caller":"traceutil/trace.go:171","msg":"trace[47353738] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:611; }","duration":"402.653833ms","start":"2026-05-19T17:51:18.724081Z","end":"2026-05-19T17:51:19.126735Z","steps":["trace[47353738] 'agreement among raft nodes before linearized reading' (duration: 402.620522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.126764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.724069Z","time spent":"402.689695ms","remote":"127.0.0.1:40436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:51:19.126846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.849147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.126868Z","caller":"traceutil/trace.go:171","msg":"trace[1277568332] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:611; }","duration":"402.898049ms","start":"2026-05-19T17:51:18.723964Z","end":"2026-05-19T17:51:19.126862Z","steps":["trace[1277568332] 'agreement among raft nodes before linearized reading' (duration: 402.866858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.126887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.72395Z","time spent":"402.931599ms","remote":"127.0.0.1:40436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:51:19.126967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.443241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.126987Z","caller":"traceutil/trace.go:171","msg":"trace[760232172] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:611; }","duration":"427.464801ms","start":"2026-05-19T17:51:18.699517Z","end":"2026-05-19T17:51:19.126982Z","steps":["trace[760232172] 'agreement among raft nodes before linearized reading' (duration: 427.432451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.127005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.699477Z","time spent":"427.523233ms","remote":"127.0.0.1:40414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"warn","ts":"2026-05-19T17:51:19.127093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.84786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.127116Z","caller":"traceutil/trace.go:171","msg":"trace[1745524306] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:611; }","duration":"427.89351ms","start":"2026-05-19T17:51:18.699216Z","end":"2026-05-19T17:51:19.127109Z","steps":["trace[1745524306] 'agreement among raft nodes before linearized reading' (duration: 427.86174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.127134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.699209Z","time spent":"427.920352ms","remote":"127.0.0.1:40424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:51:19.127227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.171827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.127248Z","caller":"traceutil/trace.go:171","msg":"trace[1499433471] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:611; }","duration":"428.223568ms","start":"2026-05-19T17:51:18.69902Z","end":"2026-05-19T17:51:19.127243Z","steps":["trace[1499433471] 'agreement among raft nodes before linearized reading' (duration: 428.193237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.127266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.699001Z","time spent":"428.259759ms","remote":"127.0.0.1:40424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:51:19.127342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.177682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.127363Z","caller":"traceutil/trace.go:171","msg":"trace[749245456] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:611; }","duration":"477.236644ms","start":"2026-05-19T17:51:18.650121Z","end":"2026-05-19T17:51:19.127358Z","steps":["trace[749245456] 'agreement among raft nodes before linearized reading' (duration: 477.205483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.12741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.650097Z","time spent":"477.307165ms","remote":"127.0.0.1:40414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:51:19.127487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.361036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.127508Z","caller":"traceutil/trace.go:171","msg":"trace[551006852] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:611; }","duration":"477.396037ms","start":"2026-05-19T17:51:18.650107Z","end":"2026-05-19T17:51:19.127503Z","steps":["trace[551006852] 'agreement among raft nodes before linearized reading' (duration: 477.364826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.127526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.650099Z","time spent":"477.421617ms","remote":"127.0.0.1:40414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:51:19.127592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.640449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:19.127613Z","caller":"traceutil/trace.go:171","msg":"trace[2080491065] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:611; }","duration":"522.69858ms","start":"2026-05-19T17:51:18.604909Z","end":"2026-05-19T17:51:19.127608Z","steps":["trace[2080491065] 'agreement among raft nodes before linearized reading' (duration: 522.668199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.127631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:18.604888Z","time spent":"522.737681ms","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-05-19T17:51:26.597159Z","caller":"traceutil/trace.go:171","msg":"trace[183609795] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"500.621439ms","start":"2026-05-19T17:51:26.096469Z","end":"2026-05-19T17:51:26.59709Z","steps":["trace[183609795] 'read index received' (duration: 493.649223ms)","trace[183609795] 'applied index is now lower than readState.Index' (duration: 6.971166ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:26.597242Z","caller":"traceutil/trace.go:171","msg":"trace[204617828] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"799.949914ms","start":"2026-05-19T17:51:25.797264Z","end":"2026-05-19T17:51:26.597214Z","steps":["trace[204617828] 'process raft request' (duration: 797.433057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:26.597615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:25.797233Z","time spent":"800.286211ms","remote":"127.0.0.1:36336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:26.597707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.195202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qqmmq\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-19T17:51:26.597794Z","caller":"traceutil/trace.go:171","msg":"trace[1298703706] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qqmmq; range_end:; response_count:1; response_revision:649; }","duration":"501.347445ms","start":"2026-05-19T17:51:26.096429Z","end":"2026-05-19T17:51:26.597777Z","steps":["trace[1298703706] 'agreement among raft nodes before linearized reading' (duration: 501.033088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:26.597833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:26.09641Z","time spent":"501.414827ms","remote":"127.0.0.1:36426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-qqmmq\" "} {"level":"warn","ts":"2026-05-19T17:51:27.728154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738095899592564,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:51:27.867511Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.240816575s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:51:27.86792Z","caller":"traceutil/trace.go:171","msg":"trace[639560106] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.241313097s","start":"2026-05-19T17:51:26.626582Z","end":"2026-05-19T17:51:27.867895Z","steps":["trace[639560106] 'process raft request' (duration: 1.241135682s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:27.868069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:26.626552Z","time spent":"1.24143422s","remote":"127.0.0.1:36426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:28.752595Z","caller":"traceutil/trace.go:171","msg":"trace[1770179973] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"1.524508601s","start":"2026-05-19T17:51:27.228057Z","end":"2026-05-19T17:51:28.752566Z","steps":["trace[1770179973] 'read index received' (duration: 639.683193ms)","trace[1770179973] 'applied index is now lower than readState.Index' (duration: 884.824448ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:28.752713Z","caller":"traceutil/trace.go:171","msg":"trace[1920123944] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"2.095489829s","start":"2026-05-19T17:51:26.657209Z","end":"2026-05-19T17:51:28.752698Z","steps":["trace[1920123944] 'process raft request' (duration: 2.095226683s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:28.752804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:26.657177Z","time spent":"2.09556491s","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:51:28.752995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.492713689s","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-19T17:51:28.753062Z","caller":"traceutil/trace.go:171","msg":"trace[727339078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"1.492832232s","start":"2026-05-19T17:51:27.260214Z","end":"2026-05-19T17:51:28.753046Z","steps":["trace[727339078] 'agreement among raft nodes before linearized reading' (duration: 1.492600987s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:28.753093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:27.260195Z","time spent":"1.492891825s","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T17:51:28.753292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525228579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-19T17:51:28.753328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485617312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T17:51:28.753341Z","caller":"traceutil/trace.go:171","msg":"trace[467943631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"1.52531945s","start":"2026-05-19T17:51:27.22801Z","end":"2026-05-19T17:51:28.75333Z","steps":["trace[467943631] 'agreement among raft nodes before linearized reading' (duration: 1.525129586s)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:28.753363Z","caller":"traceutil/trace.go:171","msg":"trace[980178922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"1.485675034s","start":"2026-05-19T17:51:27.267678Z","end":"2026-05-19T17:51:28.753353Z","steps":["trace[980178922] 'agreement among raft nodes before linearized reading' (duration: 1.485580372s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:28.753375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:27.22799Z","time spent":"1.525378402s","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T17:51:28.753393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:27.267667Z","time spent":"1.485720304s","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:51:28.753516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.492982456s","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-19T17:51:28.753548Z","caller":"traceutil/trace.go:171","msg":"trace[140581155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"1.493032978s","start":"2026-05-19T17:51:27.260506Z","end":"2026-05-19T17:51:28.753539Z","steps":["trace[140581155] 'agreement among raft nodes before linearized reading' (duration: 1.492952016s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:28.753573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:27.260494Z","time spent":"1.493072818s","remote":"127.0.0.1:36534","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-19T17:51:28.754764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001268092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:28.754956Z","caller":"traceutil/trace.go:171","msg":"trace[961866728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"1.003167345s","start":"2026-05-19T17:51:27.751754Z","end":"2026-05-19T17:51:28.754921Z","steps":["trace[961866728] 'agreement among raft nodes before linearized reading' (duration: 1.00117306s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:28.755083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:27.751696Z","time spent":"1.00337102s","remote":"127.0.0.1:36268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:51:30.276441Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738095899592575,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:51:30.77663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738095899592575,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:51:30.97659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.767399651s","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-19T17:51:30.976713Z","caller":"traceutil/trace.go:171","msg":"trace[1784190691] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:655; }","duration":"1.767530784s","start":"2026-05-19T17:51:29.209162Z","end":"2026-05-19T17:51:30.976693Z","steps":["trace[1784190691] 'range keys from in-memory index tree' (duration: 1.76737073s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:31.277234Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738095899592575,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:51:31.700017Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.482981584s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:51:31.700731Z","caller":"traceutil/trace.go:171","msg":"trace[1210915758] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"1.925141603s","start":"2026-05-19T17:51:29.775573Z","end":"2026-05-19T17:51:31.700715Z","steps":["trace[1210915758] 'read index received' (duration: 1.92456412s)","trace[1210915758] 'applied index is now lower than readState.Index' (duration: 576.913µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:31.700792Z","caller":"traceutil/trace.go:171","msg":"trace[936798059] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"2.485290416s","start":"2026-05-19T17:51:29.215496Z","end":"2026-05-19T17:51:31.700786Z","steps":["trace[936798059] 'process raft request' (duration: 2.484839266s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:31.700869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:29.215466Z","time spent":"2.485344786s","remote":"127.0.0.1:36336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036269,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:31.701332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.892366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-19T17:51:31.701362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925791777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.20\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T17:51:31.701405Z","caller":"traceutil/trace.go:171","msg":"trace[912227241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"897.990008ms","start":"2026-05-19T17:51:30.803398Z","end":"2026-05-19T17:51:31.701388Z","steps":["trace[912227241] 'agreement among raft nodes before linearized reading' (duration: 897.818344ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:31.701436Z","caller":"traceutil/trace.go:171","msg":"trace[566100968] range","detail":"{range_begin:/registry/masterleases/199.19.213.20; range_end:; response_count:1; response_revision:656; }","duration":"1.92587347s","start":"2026-05-19T17:51:29.775524Z","end":"2026-05-19T17:51:31.701398Z","steps":["trace[566100968] 'agreement among raft nodes before linearized reading' (duration: 1.925732137s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:31.701451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:30.803387Z","time spent":"898.055369ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:51:31.701329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.764902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:51:31.701486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.878697ms","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-19T17:51:31.701509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.575112ms","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-19T17:51:31.701522Z","caller":"traceutil/trace.go:171","msg":"trace[1017269801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"950.986178ms","start":"2026-05-19T17:51:30.750511Z","end":"2026-05-19T17:51:31.701497Z","steps":["trace[1017269801] 'agreement among raft nodes before linearized reading' (duration: 950.766483ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:31.701543Z","caller":"traceutil/trace.go:171","msg":"trace[1480996569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"869.944439ms","start":"2026-05-19T17:51:30.831579Z","end":"2026-05-19T17:51:31.701523Z","steps":["trace[1480996569] 'agreement among raft nodes before linearized reading' (duration: 869.807316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:31.701565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:30.750484Z","time spent":"951.071319ms","remote":"127.0.0.1:36268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T17:51:31.701567Z","caller":"traceutil/trace.go:171","msg":"trace[1396673384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"870.651135ms","start":"2026-05-19T17:51:30.830897Z","end":"2026-05-19T17:51:31.701548Z","steps":["trace[1396673384] 'agreement among raft nodes before linearized reading' (duration: 870.508412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:31.701592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:30.83157Z","time spent":"870.00723ms","remote":"127.0.0.1:36534","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-19T17:51:31.701614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:30.830886Z","time spent":"870.717566ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T17:51:31.701633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.349768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-19T17:51:31.701734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418986375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:51:31.701771Z","caller":"traceutil/trace.go:171","msg":"trace[1560869763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"870.529762ms","start":"2026-05-19T17:51:30.831198Z","end":"2026-05-19T17:51:31.701728Z","steps":["trace[1560869763] 'agreement among raft nodes before linearized reading' (duration: 870.026971ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:31.701806Z","caller":"traceutil/trace.go:171","msg":"trace[1268875142] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:656; }","duration":"1.41964933s","start":"2026-05-19T17:51:30.282141Z","end":"2026-05-19T17:51:31.70179Z","steps":["trace[1268875142] 'agreement among raft nodes before linearized reading' (duration: 1.418944244s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:31.701466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:29.775505Z","time spent":"1.925954843s","remote":"127.0.0.1:36286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.20\" "} {"level":"warn","ts":"2026-05-19T17:51:31.701856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:30.282117Z","time spent":"1.419724202s","remote":"127.0.0.1:36426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:51:31.701849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:30.831188Z","time spent":"870.623484ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T17:51:32.363254Z","caller":"traceutil/trace.go:171","msg":"trace[1321879013] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"234.348882ms","start":"2026-05-19T17:51:32.128862Z","end":"2026-05-19T17:51:32.363211Z","steps":["trace[1321879013] 'process raft request' (duration: 233.941533ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:32.363944Z","caller":"traceutil/trace.go:171","msg":"trace[1749828195] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"223.859116ms","start":"2026-05-19T17:51:32.140049Z","end":"2026-05-19T17:51:32.363909Z","steps":["trace[1749828195] 'process raft request' (duration: 223.254772ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:33.976868Z","caller":"traceutil/trace.go:171","msg":"trace[1417728874] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:706; }","duration":"254.38848ms","start":"2026-05-19T17:51:33.722456Z","end":"2026-05-19T17:51:33.976844Z","steps":["trace[1417728874] 'read index received' (duration: 210.267656ms)","trace[1417728874] 'applied index is now lower than readState.Index' (duration: 44.119784ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:33.976877Z","caller":"traceutil/trace.go:171","msg":"trace[2003571677] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"472.364679ms","start":"2026-05-19T17:51:33.504485Z","end":"2026-05-19T17:51:33.97685Z","steps":["trace[2003571677] 'process raft request' (duration: 428.204534ms)","trace[2003571677] 'compare' (duration: 43.877069ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:33.977042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.592154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-19T17:51:33.977046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:33.504457Z","time spent":"472.515023ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:33.977065Z","caller":"traceutil/trace.go:171","msg":"trace[1962154198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"254.666176ms","start":"2026-05-19T17:51:33.722391Z","end":"2026-05-19T17:51:33.977058Z","steps":["trace[1962154198] 'agreement among raft nodes before linearized reading' (duration: 254.569544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:33.977104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.225207ms","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-19T17:51:33.977164Z","caller":"traceutil/trace.go:171","msg":"trace[1712463645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"251.29138ms","start":"2026-05-19T17:51:33.725847Z","end":"2026-05-19T17:51:33.977138Z","steps":["trace[1712463645] 'agreement among raft nodes before linearized reading' (duration: 251.177037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:33.977437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.387259ms","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-19T17:51:33.97751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.758547ms","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-19T17:51:33.97755Z","caller":"traceutil/trace.go:171","msg":"trace[2134235794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"250.521662ms","start":"2026-05-19T17:51:33.726994Z","end":"2026-05-19T17:51:33.977516Z","steps":["trace[2134235794] 'agreement among raft nodes before linearized reading' (duration: 250.253836ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:33.977589Z","caller":"traceutil/trace.go:171","msg":"trace[814207370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"250.894401ms","start":"2026-05-19T17:51:33.726671Z","end":"2026-05-19T17:51:33.977565Z","steps":["trace[814207370] 'agreement among raft nodes before linearized reading' (duration: 250.660376ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:36.666025Z","caller":"traceutil/trace.go:171","msg":"trace[337077117] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"109.065305ms","start":"2026-05-19T17:51:36.556937Z","end":"2026-05-19T17:51:36.666002Z","steps":["trace[337077117] 'read index received' (duration: 108.817799ms)","trace[337077117] 'applied index is now lower than readState.Index' (duration: 246.505µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:36.666458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.505454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-05-19T17:51:36.666511Z","caller":"traceutil/trace.go:171","msg":"trace[1770437460] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:739; }","duration":"109.612516ms","start":"2026-05-19T17:51:36.556882Z","end":"2026-05-19T17:51:36.666494Z","steps":["trace[1770437460] 'agreement among raft nodes before linearized reading' (duration: 109.213268ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:36.666601Z","caller":"traceutil/trace.go:171","msg":"trace[53171505] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"291.153498ms","start":"2026-05-19T17:51:36.375415Z","end":"2026-05-19T17:51:36.666569Z","steps":["trace[53171505] 'process raft request' (duration: 290.443712ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:45.17259Z","caller":"traceutil/trace.go:171","msg":"trace[544366731] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"453.918955ms","start":"2026-05-19T17:51:44.718647Z","end":"2026-05-19T17:51:45.172566Z","steps":["trace[544366731] 'process raft request' (duration: 453.767452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:45.172798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:44.718631Z","time spent":"454.034927ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:48.619744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738095899592908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:51:49.120449Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738095899592908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:51:49.444053Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.32701151s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:51:49.444622Z","caller":"traceutil/trace.go:171","msg":"trace[343278799] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"1.327955923s","start":"2026-05-19T17:51:48.11664Z","end":"2026-05-19T17:51:49.444596Z","steps":["trace[343278799] 'process raft request' (duration: 1.327753369s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.444889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:48.116624Z","time spent":"1.328079166s","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:49.570081Z","caller":"traceutil/trace.go:171","msg":"trace[161743040] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:856; }","duration":"1.451167659s","start":"2026-05-19T17:51:48.118884Z","end":"2026-05-19T17:51:49.570052Z","steps":["trace[161743040] 'read index received' (duration: 1.325450036s)","trace[161743040] 'applied index is now lower than readState.Index' (duration: 125.715622ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:49.570239Z","caller":"traceutil/trace.go:171","msg":"trace[376262520] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"1.328800985s","start":"2026-05-19T17:51:48.24138Z","end":"2026-05-19T17:51:49.570181Z","steps":["trace[376262520] 'process raft request' (duration: 1.328507038s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.570391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451495477s","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-19T17:51:49.57044Z","caller":"traceutil/trace.go:171","msg":"trace[301616035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:827; }","duration":"1.45160464s","start":"2026-05-19T17:51:48.118824Z","end":"2026-05-19T17:51:49.570428Z","steps":["trace[301616035] 'agreement among raft nodes before linearized reading' (duration: 1.451426906s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.57049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:48.118798Z","time spent":"1.451678311s","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T17:51:49.570493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:48.241354Z","time spent":"1.329002649s","remote":"127.0.0.1:36506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:49.570544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438440008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T17:51:49.570627Z","caller":"traceutil/trace.go:171","msg":"trace[71472986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:827; }","duration":"1.438583912s","start":"2026-05-19T17:51:48.132027Z","end":"2026-05-19T17:51:49.570611Z","steps":["trace[71472986] 'agreement among raft nodes before linearized reading' (duration: 1.438276685s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.570678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:48.132016Z","time spent":"1.438653533s","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:51:49.570836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438239044s","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-19T17:51:49.570884Z","caller":"traceutil/trace.go:171","msg":"trace[21259980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:827; }","duration":"1.438304486s","start":"2026-05-19T17:51:48.132571Z","end":"2026-05-19T17:51:49.570875Z","steps":["trace[21259980] 'agreement among raft nodes before linearized reading' (duration: 1.438213304s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.570925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:48.132559Z","time spent":"1.438359347s","remote":"127.0.0.1:36534","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-19T17:51:49.570827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.331458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-19T17:51:49.571001Z","caller":"traceutil/trace.go:171","msg":"trace[667795576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:827; }","duration":"382.495651ms","start":"2026-05-19T17:51:49.188486Z","end":"2026-05-19T17:51:49.570982Z","steps":["trace[667795576] 'agreement among raft nodes before linearized reading' (duration: 382.182034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.571085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:49.188457Z","time spent":"382.594344ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T17:51:49.969622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.393917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:51:49.969807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.990847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:49.969888Z","caller":"traceutil/trace.go:171","msg":"trace[911139111] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:831; }","duration":"178.076349ms","start":"2026-05-19T17:51:49.791789Z","end":"2026-05-19T17:51:49.969866Z","steps":["trace[911139111] 'range keys from in-memory index tree' (duration: 177.960936ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:49.969853Z","caller":"traceutil/trace.go:171","msg":"trace[1713338844] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:831; }","duration":"178.651743ms","start":"2026-05-19T17:51:49.791185Z","end":"2026-05-19T17:51:49.969836Z","steps":["trace[1713338844] 'range keys from in-memory index tree' (duration: 178.318716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.969898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.453865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:49.970124Z","caller":"traceutil/trace.go:171","msg":"trace[154966296] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:831; }","duration":"163.707001ms","start":"2026-05-19T17:51:49.806402Z","end":"2026-05-19T17:51:49.970109Z","steps":["trace[154966296] 'range keys from in-memory index tree' (duration: 163.419345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.970244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.589716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:49.970309Z","caller":"traceutil/trace.go:171","msg":"trace[2048123867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:831; }","duration":"215.684589ms","start":"2026-05-19T17:51:49.754614Z","end":"2026-05-19T17:51:49.970299Z","steps":["trace[2048123867] 'range keys from in-memory index tree' (duration: 215.531685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.970464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.334491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:51:49.970501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.989529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:49.970526Z","caller":"traceutil/trace.go:171","msg":"trace[1636115240] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:831; }","duration":"194.413203ms","start":"2026-05-19T17:51:49.776096Z","end":"2026-05-19T17:51:49.970509Z","steps":["trace[1636115240] 'count revisions from in-memory index tree' (duration: 194.307ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:49.970566Z","caller":"traceutil/trace.go:171","msg":"trace[464454593] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:831; }","duration":"210.068561ms","start":"2026-05-19T17:51:49.76048Z","end":"2026-05-19T17:51:49.970549Z","steps":["trace[464454593] 'range keys from in-memory index tree' (duration: 209.949138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.9706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.513785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:51:49.970711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.471103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:51:49.970732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.928715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.20\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T17:51:49.970771Z","caller":"traceutil/trace.go:171","msg":"trace[1041594968] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:831; }","duration":"179.538374ms","start":"2026-05-19T17:51:49.791224Z","end":"2026-05-19T17:51:49.970762Z","steps":["trace[1041594968] 'count revisions from in-memory index tree' (duration: 179.448322ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:49.970787Z","caller":"traceutil/trace.go:171","msg":"trace[974280315] range","detail":"{range_begin:/registry/masterleases/199.19.213.20; range_end:; response_count:1; response_revision:831; }","duration":"195.006568ms","start":"2026-05-19T17:51:49.775765Z","end":"2026-05-19T17:51:49.970772Z","steps":["trace[974280315] 'range keys from in-memory index tree' (duration: 194.781671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.970859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.87509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:49.970921Z","caller":"traceutil/trace.go:171","msg":"trace[902490183] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:831; }","duration":"210.967322ms","start":"2026-05-19T17:51:49.75994Z","end":"2026-05-19T17:51:49.970907Z","steps":["trace[902490183] 'range keys from in-memory index tree' (duration: 210.820709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.969723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.245893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:51:49.970881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.969744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:49.971073Z","caller":"traceutil/trace.go:171","msg":"trace[1927610184] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:831; }","duration":"165.653656ms","start":"2026-05-19T17:51:49.80541Z","end":"2026-05-19T17:51:49.971064Z","steps":["trace[1927610184] 'range keys from in-memory index tree' (duration: 164.159561ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:49.971092Z","caller":"traceutil/trace.go:171","msg":"trace[264332723] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:831; }","duration":"194.186628ms","start":"2026-05-19T17:51:49.776893Z","end":"2026-05-19T17:51:49.97108Z","steps":["trace[264332723] 'range keys from in-memory index tree' (duration: 193.945673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.970643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.08522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:49.971466Z","caller":"traceutil/trace.go:171","msg":"trace[1638356385] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:831; }","duration":"210.920361ms","start":"2026-05-19T17:51:49.760538Z","end":"2026-05-19T17:51:49.971458Z","steps":["trace[1638356385] 'count revisions from in-memory index tree' (duration: 210.06597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:49.971615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.949532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:49.970756Z","caller":"traceutil/trace.go:171","msg":"trace[93426057] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:831; }","duration":"194.6824ms","start":"2026-05-19T17:51:49.776061Z","end":"2026-05-19T17:51:49.970743Z","steps":["trace[93426057] 'range keys from in-memory index tree' (duration: 194.446703ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:49.971682Z","caller":"traceutil/trace.go:171","msg":"trace[1296074033] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:831; }","duration":"166.214849ms","start":"2026-05-19T17:51:49.805451Z","end":"2026-05-19T17:51:49.971666Z","steps":["trace[1296074033] 'count revisions from in-memory index tree' (duration: 165.919882ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:50.104295Z","caller":"traceutil/trace.go:171","msg":"trace[968704352] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:862; }","duration":"130.868592ms","start":"2026-05-19T17:51:49.973402Z","end":"2026-05-19T17:51:50.10427Z","steps":["trace[968704352] 'read index received' (duration: 130.688588ms)","trace[968704352] 'applied index is now lower than readState.Index' (duration: 178.514µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:50.104421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.995335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:50.104459Z","caller":"traceutil/trace.go:171","msg":"trace[770161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:831; }","duration":"131.065947ms","start":"2026-05-19T17:51:49.973383Z","end":"2026-05-19T17:51:50.104449Z","steps":["trace[770161] 'agreement among raft nodes before linearized reading' (duration: 130.983545ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:50.380709Z","caller":"traceutil/trace.go:171","msg":"trace[1981830617] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"145.657982ms","start":"2026-05-19T17:51:50.235028Z","end":"2026-05-19T17:51:50.380686Z","steps":["trace[1981830617] 'process raft request' (duration: 145.57476ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:50.748981Z","caller":"traceutil/trace.go:171","msg":"trace[851811707] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"155.282422ms","start":"2026-05-19T17:51:50.593675Z","end":"2026-05-19T17:51:50.748957Z","steps":["trace[851811707] 'read index received' (duration: 155.053907ms)","trace[851811707] 'applied index is now lower than readState.Index' (duration: 225.575µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:50.749048Z","caller":"traceutil/trace.go:171","msg":"trace[1703835472] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"170.376726ms","start":"2026-05-19T17:51:50.578651Z","end":"2026-05-19T17:51:50.749028Z","steps":["trace[1703835472] 'process raft request' (duration: 170.128371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:50.749129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.429135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:51:50.749179Z","caller":"traceutil/trace.go:171","msg":"trace[745028616] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:834; }","duration":"155.541658ms","start":"2026-05-19T17:51:50.593624Z","end":"2026-05-19T17:51:50.749166Z","steps":["trace[745028616] 'agreement among raft nodes before linearized reading' (duration: 155.425715ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:04.015165Z","caller":"traceutil/trace.go:171","msg":"trace[613999996] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"117.331038ms","start":"2026-05-19T17:52:03.897805Z","end":"2026-05-19T17:52:04.015136Z","steps":["trace[613999996] 'process raft request' (duration: 93.189101ms)","trace[613999996] 'compare' (duration: 23.894571ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:05.555627Z","caller":"traceutil/trace.go:171","msg":"trace[815329291] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"106.176434ms","start":"2026-05-19T17:52:05.449421Z","end":"2026-05-19T17:52:05.555598Z","steps":["trace[815329291] 'read index received' (duration: 44.768054ms)","trace[815329291] 'applied index is now lower than readState.Index' (duration: 61.4066ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:52:05.556133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.663525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:52:05.556253Z","caller":"traceutil/trace.go:171","msg":"trace[1257348453] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1006; }","duration":"106.85562ms","start":"2026-05-19T17:52:05.449367Z","end":"2026-05-19T17:52:05.556222Z","steps":["trace[1257348453] 'agreement among raft nodes before linearized reading' (duration: 106.579704ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:28.147995Z","caller":"traceutil/trace.go:171","msg":"trace[13966679] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"170.359854ms","start":"2026-05-19T17:52:27.977608Z","end":"2026-05-19T17:52:28.147968Z","steps":["trace[13966679] 'process raft request' (duration: 90.977637ms)","trace[13966679] 'compare' (duration: 78.895134ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:28.148121Z","caller":"traceutil/trace.go:171","msg":"trace[2000619343] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"165.618403ms","start":"2026-05-19T17:52:27.982471Z","end":"2026-05-19T17:52:28.14809Z","steps":["trace[2000619343] 'process raft request' (duration: 165.516131ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:28.14812Z","caller":"traceutil/trace.go:171","msg":"trace[642075143] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"170.375494ms","start":"2026-05-19T17:52:27.977711Z","end":"2026-05-19T17:52:28.148087Z","steps":["trace[642075143] 'process raft request' (duration: 170.19038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:28.148374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.818575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:52:28.148552Z","caller":"traceutil/trace.go:171","msg":"trace[1119776397] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1088; }","duration":"111.983519ms","start":"2026-05-19T17:52:28.036437Z","end":"2026-05-19T17:52:28.14842Z","steps":["trace[1119776397] 'agreement among raft nodes before linearized reading' (duration: 111.813645ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:28.148045Z","caller":"traceutil/trace.go:171","msg":"trace[599015345] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1126; }","duration":"111.538749ms","start":"2026-05-19T17:52:28.036484Z","end":"2026-05-19T17:52:28.148023Z","steps":["trace[599015345] 'read index received' (duration: 32.355966ms)","trace[599015345] 'applied index is now lower than readState.Index' (duration: 79.181603ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:52.480846Z","caller":"traceutil/trace.go:171","msg":"trace[234245844] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"131.644204ms","start":"2026-05-19T17:52:52.349141Z","end":"2026-05-19T17:52:52.480785Z","steps":["trace[234245844] 'process raft request' (duration: 131.377048ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:34.784982Z","caller":"traceutil/trace.go:171","msg":"trace[2071938710] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"152.686863ms","start":"2026-05-19T17:53:34.63223Z","end":"2026-05-19T17:53:34.784917Z","steps":["trace[2071938710] 'process raft request' (duration: 145.157323ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:35.820603Z","caller":"traceutil/trace.go:171","msg":"trace[549444763] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"188.638928ms","start":"2026-05-19T17:53:35.631935Z","end":"2026-05-19T17:53:35.820574Z","steps":["trace[549444763] 'process raft request' (duration: 188.423263ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:35.868804Z","caller":"traceutil/trace.go:171","msg":"trace[903679954] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1390; }","duration":"166.280117ms","start":"2026-05-19T17:53:35.702489Z","end":"2026-05-19T17:53:35.868769Z","steps":["trace[903679954] 'read index received' (duration: 117.950226ms)","trace[903679954] 'applied index is now lower than readState.Index' (duration: 48.328581ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:35.868873Z","caller":"traceutil/trace.go:171","msg":"trace[1897374752] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"186.822165ms","start":"2026-05-19T17:53:35.682021Z","end":"2026-05-19T17:53:35.868843Z","steps":["trace[1897374752] 'process raft request' (duration: 186.58074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:35.869136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.600634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-05-19T17:53:35.869181Z","caller":"traceutil/trace.go:171","msg":"trace[1417604121] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1336; }","duration":"166.715897ms","start":"2026-05-19T17:53:35.702451Z","end":"2026-05-19T17:53:35.869167Z","steps":["trace[1417604121] 'agreement among raft nodes before linearized reading' (duration: 166.509682ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:38.57112Z","caller":"traceutil/trace.go:171","msg":"trace[2126585478] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"201.076206ms","start":"2026-05-19T17:53:38.370009Z","end":"2026-05-19T17:53:38.571085Z","steps":["trace[2126585478] 'process raft request' (duration: 200.8533ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:46.149952Z","caller":"traceutil/trace.go:171","msg":"trace[1045122714] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"115.135934ms","start":"2026-05-19T17:53:46.034793Z","end":"2026-05-19T17:53:46.149929Z","steps":["trace[1045122714] 'process raft request' (duration: 114.800486ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:47.064858Z","caller":"traceutil/trace.go:171","msg":"trace[62601366] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1437; }","duration":"398.171176ms","start":"2026-05-19T17:53:46.666664Z","end":"2026-05-19T17:53:47.064835Z","steps":["trace[62601366] 'read index received' (duration: 398.117495ms)","trace[62601366] 'applied index is now lower than readState.Index' (duration: 52.631µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:53:47.064982Z","caller":"traceutil/trace.go:171","msg":"trace[1159808274] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"448.926876ms","start":"2026-05-19T17:53:46.616029Z","end":"2026-05-19T17:53:47.064956Z","steps":["trace[1159808274] 'process raft request' (duration: 448.64931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:47.065233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:53:46.616014Z","time spent":"449.042668ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:53:47.065266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.452103ms","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-19T17:53:47.065316Z","caller":"traceutil/trace.go:171","msg":"trace[1115589043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1380; }","duration":"398.704269ms","start":"2026-05-19T17:53:46.666599Z","end":"2026-05-19T17:53:47.065303Z","steps":["trace[1115589043] 'agreement among raft nodes before linearized reading' (duration: 398.351191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:47.065411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:53:46.666581Z","time spent":"398.769841ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T17:53:47.140953Z","caller":"traceutil/trace.go:171","msg":"trace[710030197] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"139.455442ms","start":"2026-05-19T17:53:47.001459Z","end":"2026-05-19T17:53:47.140914Z","steps":["trace[710030197] 'process raft request' (duration: 77.761442ms)","trace[710030197] 'compare' (duration: 61.522416ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:53:47.141084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.543046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:53:47.141161Z","caller":"traceutil/trace.go:171","msg":"trace[74517561] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1382; }","duration":"419.6803ms","start":"2026-05-19T17:53:46.721467Z","end":"2026-05-19T17:53:47.141147Z","steps":["trace[74517561] 'agreement among raft nodes before linearized reading' (duration: 419.504226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:47.14122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:53:46.721448Z","time spent":"419.765132ms","remote":"127.0.0.1:40570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:53:47.141474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.337589ms","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-19T17:53:47.14154Z","caller":"traceutil/trace.go:171","msg":"trace[1294559522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1382; }","duration":"188.435061ms","start":"2026-05-19T17:53:46.953078Z","end":"2026-05-19T17:53:47.141513Z","steps":["trace[1294559522] 'agreement among raft nodes before linearized reading' (duration: 188.149945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:47.141547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.6295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T17:53:47.141571Z","caller":"traceutil/trace.go:171","msg":"trace[1333097420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1382; }","duration":"205.731863ms","start":"2026-05-19T17:53:46.935832Z","end":"2026-05-19T17:53:47.141564Z","steps":["trace[1333097420] 'agreement among raft nodes before linearized reading' (duration: 205.62396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:53:47.141656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.991897ms","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-19T17:53:47.141671Z","caller":"traceutil/trace.go:171","msg":"trace[1007246605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1382; }","duration":"182.006597ms","start":"2026-05-19T17:53:46.95966Z","end":"2026-05-19T17:53:47.141667Z","steps":["trace[1007246605] 'agreement among raft nodes before linearized reading' (duration: 181.956736ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:54:07.42927Z","caller":"traceutil/trace.go:171","msg":"trace[344907234] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"126.174683ms","start":"2026-05-19T17:54:07.303073Z","end":"2026-05-19T17:54:07.429248Z","steps":["trace[344907234] 'process raft request' (duration: 125.986949ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:54:07.42948Z","caller":"traceutil/trace.go:171","msg":"trace[1567352679] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"125.395604ms","start":"2026-05-19T17:54:07.304064Z","end":"2026-05-19T17:54:07.42946Z","steps":["trace[1567352679] 'process raft request' (duration: 125.106397ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:54:07.429928Z","caller":"traceutil/trace.go:171","msg":"trace[1997990664] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"122.29918ms","start":"2026-05-19T17:54:07.307599Z","end":"2026-05-19T17:54:07.429898Z","steps":["trace[1997990664] 'process raft request' (duration: 121.821439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:54:09.462035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.611909ms","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-19T17:54:09.462285Z","caller":"traceutil/trace.go:171","msg":"trace[1101948084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1458; }","duration":"112.834595ms","start":"2026-05-19T17:54:09.349362Z","end":"2026-05-19T17:54:09.462196Z","steps":["trace[1101948084] 'range keys from in-memory index tree' (duration: 112.473796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:54:09.462403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.869052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"info","ts":"2026-05-19T17:54:09.462516Z","caller":"traceutil/trace.go:171","msg":"trace[14887442] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1458; }","duration":"104.016255ms","start":"2026-05-19T17:54:09.358471Z","end":"2026-05-19T17:54:09.462487Z","steps":["trace[14887442] 'range keys from in-memory index tree' (duration: 103.79206ms)"],"step_count":1}